builder: mozilla-central_ubuntu64_hw_test-g4-e10s-pgo
slave: talos-linux64-ix-001
starttime: 1507936596.45
results: success (0)
revision: a31334a65a1c75638efae4452ecd271450df2ad0
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.454082) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.454580) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.454966) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.472716) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.472984) =========
bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-13 16:16:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 19.2M=0.001s
2017-10-13 16:16:37 (19.2 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.127302
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.621545) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.621901) =========
rm -rf scripts properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020787
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.671507) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:36.671844) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a31334a65a1c75638efae4452ecd271450df2ad0 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev a31334a65a1c75638efae4452ecd271450df2ad0 --destination scripts --debug']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-13 16:16:37,193 truncating revision to first 12 chars
2017-10-13 16:16:37,193 Setting DEBUG logging.
2017-10-13 16:16:37,194 attempt 1/10
2017-10-13 16:16:37,194 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a31334a65a1c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-13 16:16:37,550 unpacking tar archive at: mozilla-central-a31334a65a1c/testing/mozharness/
program finished with exit code 0
elapsedTime=0.645762
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:37.335278) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:37.338606) =========
script_repo_revision: a31334a65a1c75638efae4452ecd271450df2ad0
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:37.339007) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:37.339274) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-13 16:16:37.354821) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 43 secs) (at 2017-10-13 16:16:37.355104) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
16:16:38 INFO - MultiFileLogger online at 20171013 16:16:38 in /builds/slave/test
16:16:38 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
16:16:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
16:16:38 INFO - {'append_to_log': False,
16:16:38 INFO - 'base_work_dir': '/builds/slave/test',
16:16:38 INFO - 'blob_upload_branch': 'Firefox',
16:16:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
16:16:38 INFO - 'branch': 'Firefox',
16:16:38 INFO - 'buildbot_json_path': 'buildprops.json',
16:16:38 INFO - 'code_coverage': False,
16:16:38 INFO - 'config_files': ('talos/linux_config.py',),
16:16:38 INFO - 'default_actions': ('clobber',
16:16:38 INFO - 'read-buildbot-config',
16:16:38 INFO - 'download-and-extract',
16:16:38 INFO - 'populate-webroot',
16:16:38 INFO - 'create-virtualenv',
16:16:38 INFO - 'install',
16:16:38 INFO - 'setup-mitmproxy',
16:16:38 INFO - 'run-tests'),
16:16:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
16:16:38 INFO - 'disable_ccov_upload': False,
16:16:38 INFO - 'disable_stylo': False,
16:16:38 INFO - 'download_minidump_stackwalk': True,
16:16:38 INFO - 'download_symbols': 'ondemand',
16:16:38 INFO - 'enable_stylo': False,
16:16:38 INFO - 'enable_webrender': False,
16:16:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
16:16:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
16:16:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
16:16:38 INFO - 'gecko_profile': False,
16:16:38 INFO - 'gecko_profile_interval': 0,
16:16:38 INFO - 'installer_path': 'installer.exe',
16:16:38 INFO - 'log_level': 'info',
16:16:38 INFO - 'log_name': 'talos',
16:16:38 INFO - 'log_to_console': True,
16:16:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
16:16:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
16:16:38 INFO - 'opt_config_files': (),
16:16:38 INFO - 'pip_index': False,
16:16:38 INFO - 'suite': 'g4-e10s',
16:16:38 INFO - 'system_bits': '32',
16:16:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
16:16:38 INFO - 'title': 'talos-linux64-ix-001',
16:16:38 INFO - 'tooltool_cache': '/builds/tooltool_cache',
16:16:38 INFO - 'use_talos_json': True,
16:16:38 INFO - 'verify': 'False',
16:16:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
16:16:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:16:38 INFO - 'work_dir': 'build'}
16:16:38 INFO - [mozharness: 2017-10-13 23:16:38.024658Z] Running clobber step.
16:16:38 INFO - Running pre-action listener: _resource_record_pre_action
16:16:38 INFO - Running main action method: clobber
16:16:38 INFO - rmtree: /builds/slave/test/build
16:16:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
16:16:39 INFO - Running post-action listener: _resource_record_post_action
16:16:39 INFO - [mozharness: 2017-10-13 23:16:39.146400Z] Finished clobber step (success)
16:16:39 INFO - [mozharness: 2017-10-13 23:16:39.146495Z] Running read-buildbot-config step.
16:16:39 INFO - Running pre-action listener: _resource_record_pre_action
16:16:39 INFO - Running main action method: read_buildbot_config
16:16:39 INFO - Using buildbot properties:
16:16:39 INFO - {
16:16:39 INFO - "project": "",
16:16:39 INFO - "product": "firefox",
16:16:39 INFO - "who": "archaeopteryx@coole-files.de",
16:16:39 INFO - "installer_path": "public/build/target.tar.bz2",
16:16:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
16:16:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
16:16:39 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-e10s",
16:16:39 INFO - "stage_platform": "linux64-pgo",
16:16:39 INFO - "basedir": "/builds/slave/test",
16:16:39 INFO - "buildnumber": 232,
16:16:39 INFO - "platform": "ubuntu64_hw",
16:16:39 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
16:16:39 INFO - "slavebuilddir": "test",
16:16:39 INFO - "taskId": "MDtMjj6dQA6r0BkNI9vCPg",
16:16:39 INFO - "branch": "mozilla-central",
16:16:39 INFO - "script_repo_revision": "production",
16:16:39 INFO - "revision": "a31334a65a1c75638efae4452ecd271450df2ad0",
16:16:39 INFO - "slavename": "talos-linux64-ix-001",
16:16:39 INFO - "repo_path": "mozilla-central"
16:16:39 INFO - }
16:16:39 INFO - Finding installer, test and symbols from parent task.
16:16:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MDtMjj6dQA6r0BkNI9vCPg'}, attempt #1
16:16:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MDtMjj6dQA6r0BkNI9vCPg'}, attempt #1
16:16:40 INFO - Task dependencies: YvDagPrjRKSfL6iYEaFMgA
16:16:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA'}, attempt #1
16:16:42 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2
16:16:42 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.test_packages.json
16:16:42 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.crashreporter-symbols.zip
16:16:42 INFO - Running post-action listener: _resource_record_post_action
16:16:42 INFO - [mozharness: 2017-10-13 23:16:42.756292Z] Finished read-buildbot-config step (success)
16:16:42 INFO - [mozharness: 2017-10-13 23:16:42.756508Z] Running download-and-extract step.
16:16:42 INFO - Running pre-action listener: _resource_record_pre_action
16:16:42 INFO - Running main action method: download_and_extract
16:16:42 INFO - mkdir: /builds/slave/test/build/tests
16:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:16:42 INFO - trying https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.test_packages.json
16:16:42 INFO - Downloading https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
16:16:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
16:16:43 INFO - Downloaded 989 bytes.
16:16:43 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
16:16:43 INFO - Using the following test package requirements:
16:16:43 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
16:16:43 INFO - u'common': [u'target.common.tests.zip'],
16:16:43 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
16:16:43 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
16:16:43 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
16:16:43 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
16:16:43 INFO - u'mozbase': [u'target.common.tests.zip'],
16:16:43 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
16:16:43 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
16:16:43 INFO - u'web-platform': [u'target.common.tests.zip',
16:16:43 INFO - u'target.web-platform.tests.tar.gz'],
16:16:43 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
16:16:43 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
16:16:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.common.tests.zip
16:16:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.common.tests.zip'}, attempt #1
16:16:43 INFO - Fetch https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.common.tests.zip into memory
16:16:44 INFO - Content-Length response header: 56532557
16:16:44 INFO - Bytes received: 56532557
16:16:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.talos.tests.zip
16:16:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
16:16:55 INFO - Fetch https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.talos.tests.zip into memory
16:16:56 INFO - Content-Length response header: 18737020
16:16:56 INFO - Bytes received: 18737020
16:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:16:57 INFO - trying https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2
16:16:57 INFO - Downloading https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
16:16:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
16:16:58 INFO - Downloaded 63016324 bytes.
16:16:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2
16:16:58 INFO - mkdir: /builds/slave/test/properties
16:16:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
16:16:58 INFO - Writing to file /builds/slave/test/properties/build_url
16:16:58 INFO - Contents:
16:16:58 INFO - build_url:https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2
16:16:58 INFO - Running post-action listener: _resource_record_post_action
16:16:58 INFO - Running post-action listener: find_tests_for_verification
16:16:58 INFO - Running post-action listener: set_extra_try_arguments
16:16:58 INFO - [mozharness: 2017-10-13 23:16:58.977965Z] Finished download-and-extract step (success)
16:16:58 INFO - [mozharness: 2017-10-13 23:16:58.978162Z] Running populate-webroot step.
16:16:58 INFO - Running pre-action listener: _resource_record_pre_action
16:16:58 INFO - Running main action method: populate_webroot
16:16:58 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
16:16:58 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['tresize',
16:16:58 INFO - 'tcanvasmark']},
16:16:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
16:16:58 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['dromaeo_css',
16:16:58 INFO - 'kraken']},
16:16:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:16:58 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:16:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'tests': ['damp', 'tps']},
16:16:58 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['damp', 'tps']},
16:16:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
16:16:58 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['dromaeo_dom']},
16:16:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
16:16:58 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['basic_compositor_video',
16:16:58 INFO - 'glvideo']},
16:16:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
16:16:58 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['ts_paint_webext',
16:16:58 INFO - 'tp5o_webext']},
16:16:58 INFO - 'other-e10s': {'tests': ['a11yr',
16:16:58 INFO - 'ts_paint',
16:16:58 INFO - 'tpaint',
16:16:58 INFO - 'sessionrestore',
16:16:58 INFO - 'sessionrestore_many_windows',
16:16:58 INFO - 'sessionrestore_no_auto_restore',
16:16:58 INFO - 'tabpaint',
16:16:58 INFO - 'cpstartup']},
16:16:58 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['a11yr',
16:16:58 INFO - 'ts_paint',
16:16:58 INFO - 'tpaint',
16:16:58 INFO - 'sessionrestore',
16:16:58 INFO - 'sessionrestore_many_windows',
16:16:58 INFO - 'sessionrestore_no_auto_restore',
16:16:58 INFO - 'tabpaint',
16:16:58 INFO - 'cpstartup']},
16:16:58 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
16:16:58 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
16:16:58 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['perf_reftest_singletons']},
16:16:58 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['perf_reftest']},
16:16:58 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
16:16:58 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['speedometer']},
16:16:58 INFO - 'svgr-e10s': {'tests': ['tsvgx',
16:16:58 INFO - 'tsvgr_opacity',
16:16:58 INFO - 'tart',
16:16:58 INFO - 'tscrollx',
16:16:58 INFO - 'tsvg_static']},
16:16:58 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['tsvgx',
16:16:58 INFO - 'tsvgr_opacity',
16:16:58 INFO - 'tart',
16:16:58 INFO - 'tscrollx',
16:16:58 INFO - 'tsvg_static']},
16:16:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
16:16:58 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'talos_options': ['--disable-stylo'],
16:16:58 INFO - 'tests': ['tp5o']},
16:16:58 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:16:58 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:16:58 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:16:58 INFO - 'talos_options': ['--mitmproxy',
16:16:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:16:58 INFO - '--firstNonBlankPaint'],
16:16:58 INFO - 'tests': ['tp6_google',
16:16:58 INFO - 'tp6_youtube',
16:16:58 INFO - 'tp6_amazon',
16:16:58 INFO - 'tp6_facebook']},
16:16:58 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:16:58 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:16:58 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:16:58 INFO - 'talos_options': ['--disable-stylo',
16:16:58 INFO - '--mitmproxy',
16:16:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:16:58 INFO - '--firstNonBlankPaint'],
16:16:58 INFO - 'tests': ['tp6_google',
16:16:58 INFO - 'tp6_youtube',
16:16:58 INFO - 'tp6_amazon',
16:16:58 INFO - 'tp6_facebook']},
16:16:58 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:16:58 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:16:58 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:16:58 INFO - 'talos_options': ['--stylo-threads=1',
16:16:58 INFO - '--mitmproxy',
16:16:58 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:16:58 INFO - '--firstNonBlankPaint'],
16:16:58 INFO - 'tests': ['tp6_google',
16:16:58 INFO - 'tp6_youtube',
16:16:58 INFO - 'tp6_amazon',
16:16:58 INFO - 'tp6_facebook']},
16:16:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'talos_options': ['--xperf_path',
16:16:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:16:58 INFO - 'tests': ['tp5n']},
16:16:58 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:16:58 INFO - 'talos_options': ['--disable-stylo',
16:16:58 INFO - '--xperf_path',
16:16:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:16:58 INFO - 'tests': ['tp5n']}}}
16:16:58 INFO - Running post-action listener: _resource_record_post_action
16:16:58 INFO - [mozharness: 2017-10-13 23:16:58.999532Z] Finished populate-webroot step (success)
16:16:58 INFO - [mozharness: 2017-10-13 23:16:58.999620Z] Running create-virtualenv step.
16:16:58 INFO - Running pre-action listener: _resource_record_pre_action
16:16:58 INFO - Running main action method: create_virtualenv
16:16:58 INFO - Creating virtualenv /builds/slave/test/build/venv
16:16:59 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build
16:16:59 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv
16:16:59 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
16:16:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
16:16:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python
16:16:59 INFO - Installing distribute.............................................................................................................................................................................................done.
16:16:59 INFO - Installing pip...............done.
16:16:59 INFO - Return code: 0
16:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version']
16:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version
16:17:00 INFO - Reading from file tmpfile_stdout
16:17:00 INFO - Output received:
16:17:00 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7)
16:17:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
16:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build
16:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5
16:17:00 INFO - Using env: {'DISPLAY': ':0',
16:17:00 INFO - 'HOME': '/home/cltbld',
16:17:00 INFO - 'LANG': 'en_US.UTF-8',
16:17:00 INFO - 'LANGUAGE': 'en_US:en',
16:17:00 INFO - 'LOGNAME': 'cltbld',
16:17:00 INFO - 'MAIL': '/var/mail/cltbld',
16:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:00 INFO - 'MOZ_NO_REMOTE': '1',
16:17:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:00 INFO - 'NO_EM_RESTART': '1',
16:17:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:00 INFO - 'PWD': '/builds/slave/test',
16:17:00 INFO - 'SHELL': '/bin/bash',
16:17:00 INFO - 'SHLVL': '1',
16:17:00 INFO - 'TERM': 'linux',
16:17:00 INFO - 'TMOUT': '86400',
16:17:00 INFO - 'USER': 'cltbld',
16:17:00 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:00 INFO - '_': '/tools/buildbot/bin/python'}
16:17:01 INFO - Ignoring indexes: http://pypi.python.org/simple/
16:17:01 INFO - Downloading/unpacking pip>=1.5
16:17:01 INFO - Running setup.py egg_info for package pip
16:17:01 INFO - warning: no files found matching 'pip/cacert.pem'
16:17:01 INFO - warning: no files found matching '*.html' under directory 'docs'
16:17:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build'
16:17:01 INFO - no previously-included directories found matching 'docs/_build/_sources'
16:17:01 INFO - Installing collected packages: pip
16:17:01 INFO - Found existing installation: pip 1.1
16:17:01 INFO - Uninstalling pip:
16:17:01 INFO - Successfully uninstalled pip
16:17:01 INFO - Running setup.py install for pip
16:17:01 INFO - warning: no files found matching 'pip/cacert.pem'
16:17:01 INFO - warning: no files found matching '*.html' under directory 'docs'
16:17:01 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build'
16:17:01 INFO - no previously-included directories found matching 'docs/_build/_sources'
16:17:01 INFO - Installing pip script to /builds/slave/test/build/venv/bin
16:17:01 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin
16:17:01 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin
16:17:01 INFO - Successfully installed pip
16:17:01 INFO - Cleaning up...
16:17:01 INFO - Return code: 0
16:17:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
16:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build
16:17:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1
16:17:01 INFO - Using env: {'DISPLAY': ':0',
16:17:01 INFO - 'HOME': '/home/cltbld',
16:17:01 INFO - 'LANG': 'en_US.UTF-8',
16:17:01 INFO - 'LANGUAGE': 'en_US:en',
16:17:01 INFO - 'LOGNAME': 'cltbld',
16:17:01 INFO - 'MAIL': '/var/mail/cltbld',
16:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:01 INFO - 'MOZ_NO_REMOTE': '1',
16:17:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:01 INFO - 'NO_EM_RESTART': '1',
16:17:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:01 INFO - 'PWD': '/builds/slave/test',
16:17:01 INFO - 'SHELL': '/bin/bash',
16:17:01 INFO - 'SHLVL': '1',
16:17:01 INFO - 'TERM': 'linux',
16:17:01 INFO - 'TMOUT': '86400',
16:17:01 INFO - 'USER': 'cltbld',
16:17:01 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:01 INFO - '_': '/tools/buildbot/bin/python'}
16:17:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:01 INFO - Downloading/unpacking psutil>=3.1.1
16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
16:17:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
16:17:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
16:17:02 INFO - Installing collected packages: psutil
16:17:02 INFO - Running setup.py install for psutil
16:17:02 INFO - building 'psutil._psutil_linux' extension
16:17:02 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o
16:17:02 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so
16:17:03 INFO - building 'psutil._psutil_posix' extension
16:17:03 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o
16:17:03 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so
16:17:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
16:17:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
16:17:03 INFO - Successfully installed psutil
16:17:03 INFO - Cleaning up...
16:17:03 INFO - Return code: 0
16:17:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
16:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
16:17:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3
16:17:03 INFO - Using env: {'DISPLAY': ':0',
16:17:03 INFO - 'HOME': '/home/cltbld',
16:17:03 INFO - 'LANG': 'en_US.UTF-8',
16:17:03 INFO - 'LANGUAGE': 'en_US:en',
16:17:03 INFO - 'LOGNAME': 'cltbld',
16:17:03 INFO - 'MAIL': '/var/mail/cltbld',
16:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:03 INFO - 'MOZ_NO_REMOTE': '1',
16:17:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:03 INFO - 'NO_EM_RESTART': '1',
16:17:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:03 INFO - 'PWD': '/builds/slave/test',
16:17:03 INFO - 'SHELL': '/bin/bash',
16:17:03 INFO - 'SHLVL': '1',
16:17:03 INFO - 'TERM': 'linux',
16:17:03 INFO - 'TMOUT': '86400',
16:17:03 INFO - 'USER': 'cltbld',
16:17:03 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:03 INFO - '_': '/tools/buildbot/bin/python'}
16:17:03 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:03 INFO - Downloading/unpacking mozsystemmonitor==0.3
16:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz
16:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
16:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
16:17:04 INFO - Installing collected packages: mozsystemmonitor
16:17:04 INFO - Running setup.py install for mozsystemmonitor
16:17:04 INFO - Successfully installed mozsystemmonitor
16:17:05 INFO - Cleaning up...
16:17:05 INFO - Return code: 0
16:17:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
16:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build
16:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1
16:17:05 INFO - Using env: {'DISPLAY': ':0',
16:17:05 INFO - 'HOME': '/home/cltbld',
16:17:05 INFO - 'LANG': 'en_US.UTF-8',
16:17:05 INFO - 'LANGUAGE': 'en_US:en',
16:17:05 INFO - 'LOGNAME': 'cltbld',
16:17:05 INFO - 'MAIL': '/var/mail/cltbld',
16:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:05 INFO - 'MOZ_NO_REMOTE': '1',
16:17:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:05 INFO - 'NO_EM_RESTART': '1',
16:17:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:05 INFO - 'PWD': '/builds/slave/test',
16:17:05 INFO - 'SHELL': '/bin/bash',
16:17:05 INFO - 'SHLVL': '1',
16:17:05 INFO - 'TERM': 'linux',
16:17:05 INFO - 'TMOUT': '86400',
16:17:05 INFO - 'USER': 'cltbld',
16:17:05 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:05 INFO - '_': '/tools/buildbot/bin/python'}
16:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:05 INFO - Downloading/unpacking jsonschema==2.5.1
16:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
16:17:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
16:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:07 INFO - Downloading functools32-3.2.3-2.tar.gz
16:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32
16:17:07 INFO - warning: no files found matching '*.txt'
16:17:07 INFO - no previously-included directories found matching 'build'
16:17:07 INFO - no previously-included directories found matching 'dist'
16:17:07 INFO - no previously-included directories found matching '.git*'
16:17:07 INFO - Installing collected packages: jsonschema, functools32
16:17:07 INFO - Running setup.py install for functools32
16:17:07 INFO - warning: no files found matching '*.txt'
16:17:07 INFO - no previously-included directories found matching 'build'
16:17:07 INFO - no previously-included directories found matching 'dist'
16:17:07 INFO - no previously-included directories found matching '.git*'
16:17:07 INFO - Successfully installed jsonschema functools32
16:17:07 INFO - Cleaning up...
16:17:07 INFO - Return code: 0
16:17:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
16:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build
16:17:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
16:17:07 INFO - Using env: {'DISPLAY': ':0',
16:17:07 INFO - 'HOME': '/home/cltbld',
16:17:07 INFO - 'LANG': 'en_US.UTF-8',
16:17:07 INFO - 'LANGUAGE': 'en_US:en',
16:17:07 INFO - 'LOGNAME': 'cltbld',
16:17:07 INFO - 'MAIL': '/var/mail/cltbld',
16:17:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:07 INFO - 'MOZ_NO_REMOTE': '1',
16:17:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:07 INFO - 'NO_EM_RESTART': '1',
16:17:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:07 INFO - 'PWD': '/builds/slave/test',
16:17:07 INFO - 'SHELL': '/bin/bash',
16:17:07 INFO - 'SHLVL': '1',
16:17:07 INFO - 'TERM': 'linux',
16:17:07 INFO - 'TMOUT': '86400',
16:17:07 INFO - 'USER': 'cltbld',
16:17:07 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:07 INFO - '_': '/tools/buildbot/bin/python'}
16:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
16:17:08 INFO - Cleaning up...
16:17:08 INFO - Return code: 0
16:17:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
16:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build
16:17:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
16:17:08 INFO - Using env: {'DISPLAY': ':0',
16:17:08 INFO - 'HOME': '/home/cltbld',
16:17:08 INFO - 'LANG': 'en_US.UTF-8',
16:17:08 INFO - 'LANGUAGE': 'en_US:en',
16:17:08 INFO - 'LOGNAME': 'cltbld',
16:17:08 INFO - 'MAIL': '/var/mail/cltbld',
16:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:08 INFO - 'MOZ_NO_REMOTE': '1',
16:17:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:08 INFO - 'NO_EM_RESTART': '1',
16:17:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:08 INFO - 'PWD': '/builds/slave/test',
16:17:08 INFO - 'SHELL': '/bin/bash',
16:17:08 INFO - 'SHLVL': '1',
16:17:08 INFO - 'TERM': 'linux',
16:17:08 INFO - 'TMOUT': '86400',
16:17:08 INFO - 'USER': 'cltbld',
16:17:08 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:08 INFO - '_': '/tools/buildbot/bin/python'}
16:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:08 INFO - Downloading/unpacking blobuploader==1.2.4
16:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:09 INFO - Downloading blobuploader-1.2.4.tar.gz
16:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
16:17:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
16:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
16:17:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
16:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:09 INFO - Downloading docopt-0.6.1.tar.gz
16:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
16:17:09 INFO - Installing collected packages: blobuploader, requests, docopt
16:17:09 INFO - Running setup.py install for blobuploader
16:17:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
16:17:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
16:17:10 INFO - Running setup.py install for requests
16:17:10 INFO - Running setup.py install for docopt
16:17:10 INFO - Successfully installed blobuploader requests docopt
16:17:10 INFO - Cleaning up...
16:17:10 INFO - Return code: 0
16:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
16:17:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
16:17:10 INFO - Using env: {'DISPLAY': ':0',
16:17:10 INFO - 'HOME': '/home/cltbld',
16:17:10 INFO - 'LANG': 'en_US.UTF-8',
16:17:10 INFO - 'LANGUAGE': 'en_US:en',
16:17:10 INFO - 'LOGNAME': 'cltbld',
16:17:10 INFO - 'MAIL': '/var/mail/cltbld',
16:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:10 INFO - 'MOZ_NO_REMOTE': '1',
16:17:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:10 INFO - 'NO_EM_RESTART': '1',
16:17:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:10 INFO - 'PWD': '/builds/slave/test',
16:17:10 INFO - 'SHELL': '/bin/bash',
16:17:10 INFO - 'SHLVL': '1',
16:17:10 INFO - 'TERM': 'linux',
16:17:10 INFO - 'TMOUT': '86400',
16:17:10 INFO - 'USER': 'cltbld',
16:17:10 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:10 INFO - '_': '/tools/buildbot/bin/python'}
16:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
16:17:10 INFO - Running setup.py (path:/tmp/pip-90wZTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
16:17:10 INFO - Running setup.py (path:/tmp/pip-SPhjoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
16:17:10 INFO - Running setup.py (path:/tmp/pip-LxsWfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
16:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
16:17:10 INFO - Running setup.py (path:/tmp/pip-sW9syf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
16:17:11 INFO - Running setup.py (path:/tmp/pip-RywycP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
16:17:11 INFO - Running setup.py (path:/tmp/pip-eC6kUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
16:17:11 INFO - Running setup.py (path:/tmp/pip-ZgBTe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
16:17:11 INFO - Running setup.py (path:/tmp/pip-joa2F4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
16:17:11 INFO - Running setup.py (path:/tmp/pip-vkrMTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
16:17:11 INFO - Running setup.py (path:/tmp/pip-RR7zow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
16:17:11 INFO - Running setup.py (path:/tmp/pip-XsMrKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
16:17:11 INFO - Running setup.py (path:/tmp/pip-ZEo3nf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
16:17:11 INFO - Running setup.py (path:/tmp/pip-rEyjZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
16:17:11 INFO - Running setup.py (path:/tmp/pip-HY0Sia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
16:17:11 INFO - Running setup.py (path:/tmp/pip-_xY5sO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
16:17:11 INFO - Running setup.py (path:/tmp/pip-GOLOz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
16:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
16:17:11 INFO - Running setup.py (path:/tmp/pip-DzFkrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
16:17:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
16:17:11 INFO - Running setup.py install for manifestparser
16:17:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Running setup.py install for mozcrash
16:17:12 INFO - Running setup.py install for mozdebug
16:17:12 INFO - Running setup.py install for mozdevice
16:17:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Running setup.py install for mozfile
16:17:12 INFO - Running setup.py install for mozhttpd
16:17:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Running setup.py install for mozinfo
16:17:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Running setup.py install for mozInstall
16:17:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Running setup.py install for mozleak
16:17:12 INFO - Running setup.py install for mozlog
16:17:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
16:17:12 INFO - Running setup.py install for moznetwork
16:17:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
16:17:13 INFO - Running setup.py install for mozprocess
16:17:13 INFO - Running setup.py install for mozprofile
16:17:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
16:17:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
16:17:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
16:17:13 INFO - Running setup.py install for mozrunner
16:17:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
16:17:13 INFO - Running setup.py install for mozscreenshot
16:17:13 INFO - Running setup.py install for moztest
16:17:13 INFO - Running setup.py install for mozversion
16:17:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
16:17:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
16:17:13 INFO - Cleaning up...
16:17:13 INFO - Return code: 0
16:17:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config
16:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
16:17:13 INFO - Using env: {'DISPLAY': ':0',
16:17:13 INFO - 'HOME': '/home/cltbld',
16:17:13 INFO - 'LANG': 'en_US.UTF-8',
16:17:13 INFO - 'LANGUAGE': 'en_US:en',
16:17:13 INFO - 'LOGNAME': 'cltbld',
16:17:13 INFO - 'MAIL': '/var/mail/cltbld',
16:17:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:13 INFO - 'MOZ_NO_REMOTE': '1',
16:17:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:13 INFO - 'NO_EM_RESTART': '1',
16:17:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:13 INFO - 'PWD': '/builds/slave/test',
16:17:13 INFO - 'SHELL': '/bin/bash',
16:17:13 INFO - 'SHLVL': '1',
16:17:13 INFO - 'TERM': 'linux',
16:17:13 INFO - 'TMOUT': '86400',
16:17:13 INFO - 'USER': 'cltbld',
16:17:13 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:13 INFO - '_': '/tools/buildbot/bin/python'}
16:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
16:17:13 INFO - Running setup.py (path:/tmp/pip-lqz6qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
16:17:14 INFO - Running setup.py (path:/tmp/pip-qxJSR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
16:17:14 INFO - Running setup.py (path:/tmp/pip-keQSWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
16:17:14 INFO - Running setup.py (path:/tmp/pip-befLU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
16:17:14 INFO - Running setup.py (path:/tmp/pip-k9F4rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
16:17:14 INFO - Running setup.py (path:/tmp/pip-iantER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
16:17:14 INFO - Running setup.py (path:/tmp/pip-4xG4de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
16:17:14 INFO - Running setup.py (path:/tmp/pip-Iwjsup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
16:17:14 INFO - Running setup.py (path:/tmp/pip-hzOV_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
16:17:14 INFO - Running setup.py (path:/tmp/pip-GGjmBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
16:17:14 INFO - Running setup.py (path:/tmp/pip-ghd7VQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
16:17:14 INFO - Running setup.py (path:/tmp/pip-DNJPI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
16:17:14 INFO - Running setup.py (path:/tmp/pip-nwQE7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
16:17:14 INFO - Running setup.py (path:/tmp/pip-IIWMqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
16:17:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
16:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
16:17:14 INFO - Running setup.py (path:/tmp/pip-BLd_Yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
16:17:15 INFO - Running setup.py (path:/tmp/pip-XYr9pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
16:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
16:17:15 INFO - Running setup.py (path:/tmp/pip-ymQsuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
16:17:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
16:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:16 INFO - Downloading blessings-1.6.tar.gz
16:17:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
16:17:16 INFO - Installing collected packages: blessings
16:17:16 INFO - Running setup.py install for blessings
16:17:16 INFO - Successfully installed blessings
16:17:16 INFO - Cleaning up...
16:17:16 INFO - Return code: 0
16:17:16 INFO - Done creating virtualenv /builds/slave/test/build/venv.
16:17:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:17:16 INFO - Reading from file tmpfile_stdout
16:17:16 INFO - Current package versions:
16:17:16 INFO - argparse == 1.2.1
16:17:16 INFO - blessings == 1.6
16:17:16 INFO - blobuploader == 1.2.4
16:17:16 INFO - docopt == 0.6.1
16:17:16 INFO - functools32 == 3.2.3-2
16:17:16 INFO - jsonschema == 2.5.1
16:17:16 INFO - manifestparser == 1.1
16:17:16 INFO - mozInstall == 1.13
16:17:16 INFO - mozcrash == 1.0
16:17:16 INFO - mozdebug == 0.1
16:17:16 INFO - mozdevice == 0.51
16:17:16 INFO - mozfile == 1.2
16:17:16 INFO - mozhttpd == 0.7
16:17:16 INFO - mozinfo == 0.10
16:17:16 INFO - mozleak == 0.1
16:17:16 INFO - mozlog == 3.5
16:17:16 INFO - moznetwork == 0.27
16:17:16 INFO - mozprocess == 0.25
16:17:16 INFO - mozprofile == 0.28
16:17:16 INFO - mozrunner == 6.13
16:17:16 INFO - mozscreenshot == 0.1
16:17:16 INFO - mozsystemmonitor == 0.3
16:17:16 INFO - moztest == 0.8
16:17:16 INFO - mozversion == 1.4
16:17:16 INFO - psutil == 3.1.1
16:17:16 INFO - requests == 1.2.3
16:17:16 INFO - wsgiref == 0.1.2
16:17:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos
16:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
16:17:16 INFO - Using env: {'DISPLAY': ':0',
16:17:16 INFO - 'HOME': '/home/cltbld',
16:17:16 INFO - 'LANG': 'en_US.UTF-8',
16:17:16 INFO - 'LANGUAGE': 'en_US:en',
16:17:16 INFO - 'LOGNAME': 'cltbld',
16:17:16 INFO - 'MAIL': '/var/mail/cltbld',
16:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:16 INFO - 'MOZ_NO_REMOTE': '1',
16:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:16 INFO - 'NO_EM_RESTART': '1',
16:17:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:16 INFO - 'PWD': '/builds/slave/test',
16:17:16 INFO - 'SHELL': '/bin/bash',
16:17:16 INFO - 'SHLVL': '1',
16:17:16 INFO - 'TERM': 'linux',
16:17:16 INFO - 'TMOUT': '86400',
16:17:16 INFO - 'USER': 'cltbld',
16:17:16 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:16 INFO - '_': '/tools/buildbot/bin/python'}
16:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
16:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
16:17:16 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
16:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson
16:17:18 INFO - Downloading/unpacking requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
16:17:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
16:17:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
16:17:18 INFO - warning: no files found matching 'test_requests.py'
16:17:18 INFO - warning: no files found matching 'requirements.txt'
16:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
16:17:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
16:17:18 INFO - Installing collected packages: simplejson, requests
16:17:18 INFO - Running setup.py install for simplejson
16:17:18 INFO - building 'simplejson._speedups' extension
16:17:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o
16:17:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so
16:17:19 INFO - Found existing installation: requests 1.2.3
16:17:19 INFO - Uninstalling requests:
16:17:19 INFO - Successfully uninstalled requests
16:17:19 INFO - Running setup.py install for requests
16:17:19 INFO - warning: no files found matching 'test_requests.py'
16:17:19 INFO - warning: no files found matching 'requirements.txt'
16:17:19 INFO - Successfully installed simplejson requests
16:17:19 INFO - Cleaning up...
16:17:19 INFO - Return code: 0
16:17:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
16:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:17:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:17:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa7a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x214acc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:17:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build
16:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema
16:17:20 INFO - Using env: {'DISPLAY': ':0',
16:17:20 INFO - 'HOME': '/home/cltbld',
16:17:20 INFO - 'LANG': 'en_US.UTF-8',
16:17:20 INFO - 'LANGUAGE': 'en_US:en',
16:17:20 INFO - 'LOGNAME': 'cltbld',
16:17:20 INFO - 'MAIL': '/var/mail/cltbld',
16:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:20 INFO - 'MOZ_NO_REMOTE': '1',
16:17:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:20 INFO - 'NO_EM_RESTART': '1',
16:17:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:20 INFO - 'PWD': '/builds/slave/test',
16:17:20 INFO - 'SHELL': '/bin/bash',
16:17:20 INFO - 'SHLVL': '1',
16:17:20 INFO - 'TERM': 'linux',
16:17:20 INFO - 'TMOUT': '86400',
16:17:20 INFO - 'USER': 'cltbld',
16:17:20 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:20 INFO - '_': '/tools/buildbot/bin/python'}
16:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/
16:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
16:17:20 INFO - Cleaning up...
16:17:20 INFO - Return code: 0
16:17:20 INFO - Running post-action listener: _resource_record_post_action
16:17:20 INFO - Running post-action listener: _start_resource_monitoring
16:17:20 INFO - Starting resource monitoring.
16:17:20 INFO - [mozharness: 2017-10-13 23:17:20.253158Z] Finished create-virtualenv step (success)
16:17:20 INFO - [mozharness: 2017-10-13 23:17:20.253661Z] Running install step.
16:17:20 INFO - Running pre-action listener: _resource_record_pre_action
16:17:20 INFO - Running main action method: install
16:17:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:17:20 INFO - Reading from file tmpfile_stdout
16:17:20 INFO - Detecting whether we're running mozinstall >=1.0...
16:17:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
16:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
16:17:20 INFO - Reading from file tmpfile_stdout
16:17:20 INFO - Output received:
16:17:20 INFO - Usage: mozinstall [options] installer
16:17:20 INFO - Options:
16:17:20 INFO - -h, --help show this help message and exit
16:17:20 INFO - -d DEST, --destination=DEST
16:17:20 INFO - Directory to install application into. [default:
16:17:20 INFO - "/builds/slave/test"]
16:17:20 INFO - --app=APP Application being installed. [default: firefox]
16:17:20 INFO - mkdir: /builds/slave/test/build/application
16:17:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
16:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
16:17:38 INFO - Reading from file tmpfile_stdout
16:17:38 INFO - Output received:
16:17:38 INFO - /builds/slave/test/build/application/firefox/firefox
16:17:38 INFO - Running post-action listener: _resource_record_post_action
16:17:38 INFO - [mozharness: 2017-10-13 23:17:38.444032Z] Finished install step (success)
16:17:38 INFO - [mozharness: 2017-10-13 23:17:38.444282Z] Running setup-mitmproxy step.
16:17:38 INFO - Running pre-action listener: _resource_record_pre_action
16:17:38 INFO - Running main action method: setup_mitmproxy
16:17:38 INFO - Skipping: mitmproxy is not required
16:17:38 INFO - Running post-action listener: _resource_record_post_action
16:17:38 INFO - [mozharness: 2017-10-13 23:17:38.445412Z] Finished setup-mitmproxy step (success)
16:17:38 INFO - [mozharness: 2017-10-13 23:17:38.445650Z] Running run-tests step.
16:17:38 INFO - Running pre-action listener: _resource_record_pre_action
16:17:38 INFO - Running pre-action listener: _set_gcov_prefix
16:17:38 INFO - Running main action method: run_tests
16:17:38 WARNING - Try message not found.
16:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
16:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
16:17:38 INFO - Python 2.7.3
16:17:38 INFO - Return code: 0
16:17:38 INFO - grabbing minidump binary from tooltool
16:17:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:17:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2147e60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2148d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21491f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
16:17:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
16:17:38 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
16:17:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
16:17:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
16:17:38 INFO - Return code: 0
16:17:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
16:17:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
16:17:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
16:17:38 INFO - ENV: RUST_BACKTRACE is now full
16:17:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
16:17:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
16:17:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
16:17:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-001 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
16:17:38 INFO - Using env: {'DISPLAY': ':0',
16:17:38 INFO - 'HOME': '/home/cltbld',
16:17:38 INFO - 'LANG': 'en_US.UTF-8',
16:17:38 INFO - 'LANGUAGE': 'en_US:en',
16:17:38 INFO - 'LOGNAME': 'cltbld',
16:17:38 INFO - 'MAIL': '/var/mail/cltbld',
16:17:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:17:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:17:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:38 INFO - 'MOZ_NO_REMOTE': '1',
16:17:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:17:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:38 INFO - 'NO_EM_RESTART': '1',
16:17:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:38 INFO - 'PWD': '/builds/slave/test',
16:17:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:17:38 INFO - 'RUST_BACKTRACE': 'full',
16:17:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:17:38 INFO - 'SHELL': '/bin/bash',
16:17:38 INFO - 'SHLVL': '1',
16:17:38 INFO - 'TERM': 'linux',
16:17:38 INFO - 'TMOUT': '86400',
16:17:38 INFO - 'USER': 'cltbld',
16:17:38 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:38 INFO - '_': '/tools/buildbot/bin/python'}
16:17:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-001', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
16:17:38 INFO - mozversion application_buildid: 20171013213818
16:17:38 INFO - mozversion application_changeset: a31334a65a1c75638efae4452ecd271450df2ad0
16:17:38 INFO - mozversion application_display_name: Nightly
16:17:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
16:17:38 INFO - mozversion application_name: Firefox
16:17:38 INFO - mozversion application_remotingname: firefox
16:17:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
16:17:38 INFO - mozversion application_vendor: Mozilla
16:17:38 INFO - mozversion application_version: 58.0a1
16:17:38 INFO - mozversion platform_buildid: 20171013213818
16:17:38 INFO - mozversion platform_changeset: a31334a65a1c75638efae4452ecd271450df2ad0
16:17:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
16:17:38 INFO - mozversion platform_version: 58.0a1
16:17:38 INFO - using testdate: 1507936658
16:17:38 INFO - actual date: 1507936658
16:17:38 INFO - starting webserver on 'localhost:55469'
16:17:38 INFO - SUITE-START | Running 2 tests
16:17:38 INFO - TEST-START | basic_compositor_video
16:17:38 INFO - Initialising browser for basic_compositor_video test...
16:17:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55469/getInfo.html -profile /tmp/tmppAUPOf/profile
16:17:38 INFO - TEST-INFO | started process 30832 (/builds/slave/test/build/application/firefox/firefox http://localhost:55469/getInfo.html)
16:17:44 INFO - TEST-INFO | 30832: exit 0
16:17:44 INFO - Browser initialized.
16:17:44 INFO - Running cycle 1/1 for basic_compositor_video test...
16:17:44 INFO - Using env: {'DISPLAY': ':0',
16:17:44 INFO - 'HOME': '/home/cltbld',
16:17:44 INFO - 'JSGC_DISABLE_POISONING': '1',
16:17:44 INFO - 'LANG': 'en_US.UTF-8',
16:17:44 INFO - 'LANGUAGE': 'en_US:en',
16:17:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:17:44 INFO - 'LOGNAME': 'cltbld',
16:17:44 INFO - 'MAIL': '/var/mail/cltbld',
16:17:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:17:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:17:44 INFO - 'MOZ_CRASHREPORTER': '1',
16:17:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:17:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:17:44 INFO - 'MOZ_NO_REMOTE': '1',
16:17:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:17:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:17:44 INFO - 'NO_EM_RESTART': '1',
16:17:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:17:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:17:44 INFO - 'PWD': '/builds/slave/test',
16:17:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:17:44 INFO - 'RUST_BACKTRACE': 'full',
16:17:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:17:44 INFO - 'SHELL': '/bin/bash',
16:17:44 INFO - 'SHLVL': '1',
16:17:44 INFO - 'TERM': 'linux',
16:17:44 INFO - 'TMOUT': '86400',
16:17:44 INFO - 'USER': 'cltbld',
16:17:44 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:17:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:17:44 INFO - '_': '/tools/buildbot/bin/python'}
16:17:44 INFO - TEST-INFO | started process 31107 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppAUPOf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
16:17:45 INFO - PID 31107 |
16:17:45 INFO - PID 31107 | (/builds/slave/test/build/application/firefox/firefox:31156): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:17:45 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6847922515440765 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6675875 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.737573827446439 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7470131004366811 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9319266055045863 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0490789473684226 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.4646709006928393 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.419974358974361 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704115969581745 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5101652892562045 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.035267558528423 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 10.256974358974363 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.155855018587358 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639335106382978 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398202479338835 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.908690476190497 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.541653846153853 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.116527777777769 ms/frame
16:18:31 INFO - PID 31107 |
16:18:31 INFO - PID 31107 | Cycle 1(1): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:19:17 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680347144456887 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6714661654135337 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7661948204826365 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7708495575221241 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.155242382271467 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.07653455284553 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.40965340909091 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3452424749163847 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619073033707863 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.51107438016529 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.31182130584193 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 9.711893203883502 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.111444444444432 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.64066489361702 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.297295081967187 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.768147058823567 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.281973684210516 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.754354838709665 ms/frame
16:19:17 INFO - PID 31107 |
16:19:17 INFO - PID 31107 | Cycle 1(2): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:20:03 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789283715724677 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6668416666666668 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7798339264531435 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.784295272078501 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.184386331938633 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.133006198347108 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.5019428238039683 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4550604490500856 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7268416030534315 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6050140056022455 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.382266435986168 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 10.417239583333336 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.073690036900373 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.641968085106395 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.298852459016386 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.836183431952676 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.673385214007773 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.989395604395602 ms/frame
16:20:03 INFO - PID 31107 |
16:20:03 INFO - PID 31107 | Cycle 1(3): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:20:50 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810336134453785 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672541666666663 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7839952437574318 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7547236842105265 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9693584656084644 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.083469387755105 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.437554410080184 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.390271186440675 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7043250950570314 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.526408839779009 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.034782608695645 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 10.103207070707077 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.111055555555545 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.526842105263189 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606575630252124 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.270950920245404 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.494827586206902 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.929699453551883 ms/frame
16:20:50 INFO - PID 31107 |
16:20:50 INFO - PID 31107 | Cycle 1(4): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:21:36 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6788304420817013 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713533834586463 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7442209302325584 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7579569420035144 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.161386962552012 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.10694045174538 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.386365688487582 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.340926544240401 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683153409090906 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.466448087431697 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.069731543624169 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 10.31345360824741 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.07053505535056 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.700695187165769 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.29745901639345 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.840059171597597 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630406976744203 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.81686486486484 ms/frame
16:21:36 INFO - PID 31107 |
16:21:36 INFO - PID 31107 | Cycle 1(5): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:22:22 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6799440089585667 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698121869782971 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7711717827626923 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7558947368421054 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.001360000000001 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.149429460580912 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.4850522648083637 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3800760135135093 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6718620037807135 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.42238482384824 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 9.708996763754051 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 9.901905940594066 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.155390334572488 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.583280423280412 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.55573221757322 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050150602409621 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.322679245283027 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932431693989082 ms/frame
16:22:22 INFO - PID 31107 |
16:22:22 INFO - PID 31107 | Cycle 1(6): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:23:08 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810056022408966 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685446205170973 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7549210526315795 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7690937223695846 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.065975609756098 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.804020912547527 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.405686719636776 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.346162207357861 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640131578947367 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.665453257790375 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 9.967956810631227 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 10.104848484848494 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.113981481481487 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.585582010582026 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503645833333334 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.909285714285726 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.450973282442744 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.0507182320442 ms/frame
16:23:08 INFO - PID 31107 |
16:23:08 INFO - PID 31107 | Cycle 1(7): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:23:54 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6830931015143018 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668265221017515 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7215490533562827 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6881223628691977 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071892808683854 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.176356993736949 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.6101383874849597 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.546391843971626 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7145333333333355 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.435502717391302 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.275513698630139 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 10.05030150753768 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991318681318685 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640558510638312 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500187500000022 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835946745562095 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.62984496124031 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.240224719101095 ms/frame
16:23:54 INFO - PID 31107 |
16:23:54 INFO - PID 31107 | Cycle 1(8): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:24:41 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6930361173814898 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6702295492487478 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7145257142857144 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.718784364261169 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.065752032520328 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.058965517241378 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.585077658303464 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.503467600700531 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.826893203883492 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620323033707873 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.384013840830448 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 9.66393719806764 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.112907407407409 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640691489361688 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.50362499999998 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054457831325307 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452347328244272 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.053591160220979 ms/frame
16:24:41 INFO - PID 31107 |
16:24:41 INFO - PID 31107 | Cycle 1(9): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:25:27 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855449438202248 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6712113617376776 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7637242798353912 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7501968503937002 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.066056910569106 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.115730452674895 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.538260613207549 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.454792746113991 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.661235849056607 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.292592592592589 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.274554794520554 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 10.000849999999991 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.111555555555551 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.753225806451644 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347139917695456 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.632383720930257 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540615384615375 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.993159340659327 ms/frame
16:25:27 INFO - PID 31107 |
16:25:27 INFO - PID 31107 | Cycle 1(10): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:26:13 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6847697922515443 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6701836393989984 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.764097589653145 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7187585910652923 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.121497252747254 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.116234567901234 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.5764958283671042 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5032399299474646 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682159090909091 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572228412256268 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.003000000000005 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 9.56964114832536 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.11151851851852 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.757284946236537 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503645833333334 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05403614457832 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.410532319391612 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990329670329698 ms/frame
16:26:13 INFO - PID 31107 |
16:26:13 INFO - PID 31107 | Cycle 1(11): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:26:59 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855308988764044 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6668500000000002 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.763909465020576 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7457417102966848 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_startup = 4.156433518005542 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.150446058091285 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_startup = 3.4172494305239174 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1_inclip = 3.350443886097153 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5762081784386615 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.466065573770496 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_startup = 10.207295918367358 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.480p.60fps.webm_scale_2_inclip = 9.760073170731701 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.152639405204452 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.642101063829807 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.7117161016949 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.198841463414638 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.629282945736447 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.241432584269655 ms/frame
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | Cycle 1(12): loaded http://localhost:55469/tests/video/video_playback.html (next: http://localhost:55469/tests/video/video_playback.html)
16:26:59 INFO - PID 31107 | __start_tp_report
16:26:59 INFO - PID 31107 | _x_x_mozilla_page_load
16:26:59 INFO - PID 31107 | _x_x_mozilla_page_load_details
16:26:59 INFO - PID 31107 | |i|pagename|runs|
16:26:59 INFO - PID 31107 | |0;240p.120fps.mp4_scale_1_startup;1.6847922515440765;1.680347144456887;1.6789283715724677;1.6810336134453785;1.6788304420817013;1.6799440089585667;1.6810056022408966;1.6830931015143018;1.6930361173814898;1.6855449438202248;1.6847697922515443;1.6855308988764044
16:26:59 INFO - PID 31107 | |1;240p.120fps.mp4_scale_1_inclip;1.6675875;1.6714661654135337;1.6668416666666668;1.6672541666666663;1.6713533834586463;1.6698121869782971;1.6685446205170973;1.668265221017515;1.6702295492487478;1.6712113617376776;1.6701836393989984;1.6668500000000002
16:26:59 INFO - PID 31107 | |2;240p.120fps.mp4_scale_1.1_startup;1.737573827446439;1.7661948204826365;1.7798339264531435;1.7839952437574318;1.7442209302325584;1.7711717827626923;1.7549210526315795;1.7215490533562827;1.7145257142857144;1.7637242798353912;1.764097589653145;1.763909465020576
16:26:59 INFO - PID 31107 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7470131004366811;1.7708495575221241;1.784295272078501;1.7547236842105265;1.7579569420035144;1.7558947368421054;1.7690937223695846;1.6881223628691977;1.718784364261169;1.7501968503937002;1.7187585910652923;1.7457417102966848
16:26:59 INFO - PID 31107 | |4;240p.120fps.mp4_scale_2_startup;3.9319266055045863;4.155242382271467;4.184386331938633;3.9693584656084644;4.161386962552012;4.001360000000001;4.065975609756098;4.071892808683854;4.065752032520328;4.066056910569106;4.121497252747254;4.156433518005542
16:26:59 INFO - PID 31107 | |5;240p.120fps.mp4_scale_2_inclip;4.0490789473684226;4.07653455284553;4.133006198347108;4.083469387755105;4.10694045174538;4.149429460580912;3.804020912547527;4.176356993736949;4.058965517241378;4.115730452674895;4.116234567901234;4.150446058091285
16:26:59 INFO - PID 31107 | |6;480p.60fps.webm_scale_1_startup;3.4646709006928393;3.40965340909091;3.5019428238039683;3.437554410080184;3.386365688487582;3.4850522648083637;3.405686719636776;3.6101383874849597;3.585077658303464;3.538260613207549;3.5764958283671042;3.4172494305239174
16:26:59 INFO - PID 31107 | |7;480p.60fps.webm_scale_1_inclip;3.419974358974361;3.3452424749163847;3.4550604490500856;3.390271186440675;3.340926544240401;3.3800760135135093;3.346162207357861;3.546391843971626;3.503467600700531;3.454792746113991;3.5032399299474646;3.350443886097153
16:26:59 INFO - PID 31107 | |8;480p.60fps.webm_scale_1.1_startup;5.704115969581745;5.619073033707863;5.7268416030534315;5.7043250950570314;5.683153409090906;5.6718620037807135;5.640131578947367;5.7145333333333355;5.826893203883492;5.661235849056607;5.682159090909091;5.5762081784386615
16:26:59 INFO - PID 31107 | |9;480p.60fps.webm_scale_1.1_inclip;5.5101652892562045;5.51107438016529;5.6050140056022455;5.526408839779009;5.466448087431697;5.42238482384824;5.665453257790375;5.435502717391302;5.620323033707873;5.292592592592589;5.572228412256268;5.466065573770496
16:26:59 INFO - PID 31107 | |10;480p.60fps.webm_scale_2_startup;10.035267558528423;10.31182130584193;10.382266435986168;10.034782608695645;10.069731543624169;9.708996763754051;9.967956810631227;10.275513698630139;10.384013840830448;10.274554794520554;10.003000000000005;10.207295918367358
16:26:59 INFO - PID 31107 | |11;480p.60fps.webm_scale_2_inclip;10.256974358974363;9.711893203883502;10.417239583333336;10.103207070707077;10.31345360824741;9.901905940594066;10.104848484848494;10.05030150753768;9.66393719806764;10.000849999999991;9.56964114832536;9.760073170731701
16:26:59 INFO - PID 31107 | |12;1080p.60fps.mp4_scale_1_startup;11.155855018587358;11.111444444444432;11.073690036900373;11.111055555555545;11.07053505535056;11.155390334572488;11.113981481481487;10.991318681318685;11.112907407407409;11.111555555555551;11.11151851851852;11.152639405204452
16:26:59 INFO - PID 31107 | |13;1080p.60fps.mp4_scale_1_inclip;10.639335106382978;10.64066489361702;10.641968085106395;10.526842105263189;10.700695187165769;10.583280423280412;10.585582010582026;10.640558510638312;10.640691489361688;10.753225806451644;10.757284946236537;10.642101063829807
16:26:59 INFO - PID 31107 | |14;1080p.60fps.mp4_scale_1.1_startup;12.398202479338835;12.297295081967187;12.298852459016386;12.606575630252124;12.29745901639345;12.55573221757322;12.503645833333334;12.500187500000022;12.50362499999998;12.347139917695456;12.503645833333334;12.7117161016949
16:26:59 INFO - PID 31107 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.908690476190497;11.768147058823567;11.836183431952676;12.270950920245404;11.840059171597597;12.050150602409621;11.909285714285726;11.835946745562095;12.054457831325307;11.632383720930257;12.05403614457832;12.198841463414638
16:26:59 INFO - PID 31107 | |16;1080p.60fps.mp4_scale_2_startup;11.541653846153853;11.281973684210516;11.673385214007773;11.494827586206902;11.630406976744203;11.322679245283027;11.450973282442744;11.62984496124031;11.452347328244272;11.540615384615375;11.410532319391612;11.629282945736447
16:26:59 INFO - PID 31107 | |17;1080p.60fps.mp4_scale_2_inclip;11.116527777777769;10.754354838709665;10.989395604395602;10.929699453551883;10.81686486486484;10.932431693989082;11.0507182320442;11.240224719101095;11.053591160220979;10.993159340659327;10.990329670329698;11.241432584269655
16:26:59 INFO - PID 31107 | __end_tp_report
16:26:59 INFO - PID 31107 | __start_cc_report
16:26:59 INFO - PID 31107 | _x_x_mozilla_cycle_collect,570
16:26:59 INFO - PID 31107 | __end_cc_report
16:26:59 INFO - PID 31107 | __startTimestamp1507937219928__endTimestamp
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | ------- Summary: start -------
16:26:59 INFO - PID 31107 | Number of tests: 18
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
16:26:59 INFO - PID 31107 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
16:26:59 INFO - PID 31107 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.76 stddev:0.02 (1.2%) stddev-sans-first:0.02
16:26:59 INFO - PID 31107 | Values: 1.7 1.8 1.8 1.8 1.7 1.8 1.8 1.7 1.7 1.8 1.8 1.8
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.76 stddev:0.03 (1.5%) stddev-sans-first:0.03
16:26:59 INFO - PID 31107 | Values: 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.7 1.7 1.8 1.7 1.7
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.08 Median:4.10 stddev:0.08 (2.0%) stddev-sans-first:0.07
16:26:59 INFO - PID 31107 | Values: 3.9 4.2 4.2 4.0 4.2 4.0 4.1 4.1 4.1 4.1 4.1 4.2
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.09 Median:4.12 stddev:0.10 (2.3%) stddev-sans-first:0.10
16:26:59 INFO - PID 31107 | Values: 4.0 4.1 4.1 4.1 4.1 4.1 3.8 4.2 4.1 4.1 4.1 4.2
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.48 Median:3.49 stddev:0.08 (2.2%) stddev-sans-first:0.08
16:26:59 INFO - PID 31107 | Values: 3.5 3.4 3.5 3.4 3.4 3.5 3.4 3.6 3.6 3.5 3.6 3.4
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.44 stddev:0.07 (2.1%) stddev-sans-first:0.08
16:26:59 INFO - PID 31107 | Values: 3.4 3.3 3.5 3.4 3.3 3.4 3.3 3.5 3.5 3.5 3.5 3.4
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.68 Median:5.69 stddev:0.06 (1.1%) stddev-sans-first:0.06
16:26:59 INFO - PID 31107 | Values: 5.7 5.6 5.7 5.7 5.7 5.7 5.6 5.7 5.8 5.7 5.7 5.6
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.51 Median:5.52 stddev:0.10 (1.8%) stddev-sans-first:0.11
16:26:59 INFO - PID 31107 | Values: 5.5 5.5 5.6 5.5 5.5 5.4 5.7 5.4 5.6 5.3 5.6 5.5
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.14 Median:10.29 stddev:0.20 (2.0%) stddev-sans-first:0.21
16:26:59 INFO - PID 31107 | Values: 10.0 10.3 10.4 10.0 10.1 9.7 10.0 10.3 10.4 10.3 10.0 10.2
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.99 Median:9.99 stddev:0.27 (2.7%) stddev-sans-first:0.27
16:26:59 INFO - PID 31107 | Values: 10.3 9.7 10.4 10.1 10.3 9.9 10.1 10.1 9.7 10.0 9.6 9.8
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.11 Median:11.11 stddev:0.05 (0.4%) stddev-sans-first:0.04
16:26:59 INFO - PID 31107 | Values: 11.2 11.1 11.1 11.1 11.1 11.2 11.1 11.0 11.1 11.1 11.1 11.2
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.65 Median:10.64 stddev:0.07 (0.6%) stddev-sans-first:0.07
16:26:59 INFO - PID 31107 | Values: 10.6 10.6 10.6 10.5 10.7 10.6 10.6 10.6 10.6 10.8 10.8 10.6
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.46 Median:12.50 stddev:0.13 (1.1%) stddev-sans-first:0.14
16:26:59 INFO - PID 31107 | Values: 12.4 12.3 12.3 12.6 12.3 12.6 12.5 12.5 12.5 12.3 12.5 12.7
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.95 Median:11.98 stddev:0.18 (1.5%) stddev-sans-first:0.19
16:26:59 INFO - PID 31107 | Values: 11.9 11.8 11.8 12.3 11.8 12.1 11.9 11.8 12.1 11.6 12.1 12.2
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.50 Median:11.54 stddev:0.13 (1.1%) stddev-sans-first:0.13
16:26:59 INFO - PID 31107 | Values: 11.5 11.3 11.7 11.5 11.6 11.3 11.5 11.6 11.5 11.5 11.4 11.6
16:26:59 INFO - PID 31107 |
16:26:59 INFO - PID 31107 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.01 Median:11.02 stddev:0.15 (1.3%) stddev-sans-first:0.15
16:26:59 INFO - PID 31107 | Values: 11.1 10.8 11.0 10.9 10.8 10.9 11.1 11.2 11.1 11.0 11.0 11.2
16:26:59 INFO - PID 31107 | -------- Summary: end --------
16:26:59 INFO - PID 31107 |
16:27:00 INFO - TEST-INFO | 31107: exit 0
16:27:00 INFO - TEST-OK | basic_compositor_video | took 561583ms
16:27:00 INFO - TEST-START | glvideo
16:27:00 INFO - Initialising browser for glvideo test...
16:27:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55469/getInfo.html -profile /tmp/tmpAWou8V/profile
16:27:00 INFO - TEST-INFO | started process 32353 (/builds/slave/test/build/application/firefox/firefox http://localhost:55469/getInfo.html)
16:27:05 INFO - TEST-INFO | 32353: exit 0
16:27:05 INFO - Browser initialized.
16:27:05 INFO - Running cycle 1/1 for glvideo test...
16:27:05 INFO - Using env: {'DISPLAY': ':0',
16:27:05 INFO - 'HOME': '/home/cltbld',
16:27:05 INFO - 'JSGC_DISABLE_POISONING': '1',
16:27:05 INFO - 'LANG': 'en_US.UTF-8',
16:27:05 INFO - 'LANGUAGE': 'en_US:en',
16:27:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:27:05 INFO - 'LOGNAME': 'cltbld',
16:27:05 INFO - 'MAIL': '/var/mail/cltbld',
16:27:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:27:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:27:05 INFO - 'MOZ_CRASHREPORTER': '1',
16:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:27:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:27:05 INFO - 'MOZ_NO_REMOTE': '1',
16:27:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:27:05 INFO - 'NO_EM_RESTART': '1',
16:27:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:27:05 INFO - 'PWD': '/builds/slave/test',
16:27:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:27:05 INFO - 'RUST_BACKTRACE': 'full',
16:27:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:27:05 INFO - 'SHELL': '/bin/bash',
16:27:05 INFO - 'SHLVL': '1',
16:27:05 INFO - 'TERM': 'linux',
16:27:05 INFO - 'TMOUT': '86400',
16:27:05 INFO - 'USER': 'cltbld',
16:27:05 INFO - 'XDG_SESSION_COOKIE': '8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764',
16:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:27:05 INFO - '_': '/tools/buildbot/bin/python'}
16:27:05 INFO - TEST-INFO | started process 32628 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAWou8V/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
16:27:06 INFO - PID 32628 |
16:27:06 INFO - PID 32628 | (/builds/slave/test/build/application/firefox/firefox:32677): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:27:06 INFO - PID 32628 |
16:27:07 INFO - PID 32628 | [talos glvideo result] Mean tick time across 100 ticks: 6.937950000000001 ms
16:27:07 INFO - PID 32628 | Cycle 1(1): loaded http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html)
16:27:09 INFO - PID 32628 | [talos glvideo result] Mean tick time across 100 ticks: 6.462750000000001 ms
16:27:09 INFO - PID 32628 | Cycle 1(2): loaded http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html)
16:27:10 INFO - PID 32628 | [talos glvideo result] Mean tick time across 100 ticks: 6.6103 ms
16:27:10 INFO - PID 32628 | Cycle 1(3): loaded http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html)
16:27:11 INFO - PID 32628 | [talos glvideo result] Mean tick time across 100 ticks: 6.589449999999999 ms
16:27:11 INFO - PID 32628 | Cycle 1(4): loaded http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html)
16:27:13 INFO - PID 32628 | [talos glvideo result] Mean tick time across 100 ticks: 6.60215 ms
16:27:13 INFO - PID 32628 | Cycle 1(5): loaded http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55469/tests/webgl/benchmarks/video/video_upload.html)
16:27:13 INFO - PID 32628 | __start_tp_report
16:27:13 INFO - PID 32628 | _x_x_mozilla_page_load
16:27:13 INFO - PID 32628 | _x_x_mozilla_page_load_details
16:27:13 INFO - PID 32628 | |i|pagename|runs|
16:27:13 INFO - PID 32628 | |0;Mean tick time across 100 ticks: ;6.937950000000001;6.462750000000001;6.6103;6.589449999999999;6.60215
16:27:13 INFO - PID 32628 | __end_tp_report
16:27:13 INFO - PID 32628 | __start_cc_report
16:27:13 INFO - PID 32628 | _x_x_mozilla_cycle_collect,279
16:27:13 INFO - PID 32628 | __end_cc_report
16:27:13 INFO - PID 32628 | __startTimestamp1507937233502__endTimestamp
16:27:13 INFO - PID 32628 |
16:27:13 INFO - PID 32628 | ------- Summary: start -------
16:27:13 INFO - PID 32628 | Number of tests: 1
16:27:13 INFO - PID 32628 |
16:27:13 INFO - PID 32628 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.64 Median:6.60 stddev:0.18 (2.7%) stddev-sans-first:0.07
16:27:13 INFO - PID 32628 | Values: 6.9 6.5 6.6 6.6 6.6
16:27:13 INFO - PID 32628 | -------- Summary: end --------
16:27:13 INFO - PID 32628 |
16:27:13 INFO - PID 32628 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
16:27:14 INFO - TEST-INFO | 32628: exit 0
16:27:14 INFO - TEST-OK | glvideo | took 13736ms
16:27:14 INFO - SUITE-END | took 575s
16:27:14 INFO - Completed test suite (00:09:35)
16:27:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.5903134691202485, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6847922515440765, 1.680347144456887, 1.6789283715724677, 1.6810336134453785, 1.6788304420817013, 1.6799440089585667, 1.6810056022408966, 1.6830931015143018, 1.6930361173814898, 1.6855449438202248, 1.6847697922515443, 1.6855308988764044], "value": 1.6810336134453785, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6675875, 1.6714661654135337, 1.6668416666666668, 1.6672541666666663, 1.6713533834586463, 1.6698121869782971, 1.6685446205170973, 1.668265221017515, 1.6702295492487478, 1.6712113617376776, 1.6701836393989984, 1.6668500000000002], "value": 1.6698121869782971, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.737573827446439, 1.7661948204826365, 1.7798339264531435, 1.7839952437574318, 1.7442209302325584, 1.7711717827626923, 1.7549210526315795, 1.7215490533562827, 1.7145257142857144, 1.7637242798353912, 1.764097589653145, 1.763909465020576], "value": 1.763909465020576, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7470131004366811, 1.7708495575221241, 1.784295272078501, 1.7547236842105265, 1.7579569420035144, 1.7558947368421054, 1.7690937223695846, 1.6881223628691977, 1.718784364261169, 1.7501968503937002, 1.7187585910652923, 1.7457417102966848], "value": 1.7547236842105265, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9319266055045863, 4.155242382271467, 4.184386331938633, 3.9693584656084644, 4.161386962552012, 4.001360000000001, 4.065975609756098, 4.071892808683854, 4.065752032520328, 4.066056910569106, 4.121497252747254, 4.156433518005542], "value": 4.071892808683854, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.0490789473684226, 4.07653455284553, 4.133006198347108, 4.083469387755105, 4.10694045174538, 4.149429460580912, 3.804020912547527, 4.176356993736949, 4.058965517241378, 4.115730452674895, 4.116234567901234, 4.150446058091285], "value": 4.115730452674895, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4646709006928393, 3.40965340909091, 3.5019428238039683, 3.437554410080184, 3.386365688487582, 3.4850522648083637, 3.405686719636776, 3.6101383874849597, 3.585077658303464, 3.538260613207549, 3.5764958283671042, 3.4172494305239174], "value": 3.4850522648083637, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.419974358974361, 3.3452424749163847, 3.4550604490500856, 3.390271186440675, 3.340926544240401, 3.3800760135135093, 3.346162207357861, 3.546391843971626, 3.503467600700531, 3.454792746113991, 3.5032399299474646, 3.350443886097153], "value": 3.390271186440675, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.704115969581745, 5.619073033707863, 5.7268416030534315, 5.7043250950570314, 5.683153409090906, 5.6718620037807135, 5.640131578947367, 5.7145333333333355, 5.826893203883492, 5.661235849056607, 5.682159090909091, 5.5762081784386615], "value": 5.682159090909091, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.5101652892562045, 5.51107438016529, 5.6050140056022455, 5.526408839779009, 5.466448087431697, 5.42238482384824, 5.665453257790375, 5.435502717391302, 5.620323033707873, 5.292592592592589, 5.572228412256268, 5.466065573770496], "value": 5.51107438016529, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.035267558528423, 10.31182130584193, 10.382266435986168, 10.034782608695645, 10.069731543624169, 9.708996763754051, 9.967956810631227, 10.275513698630139, 10.384013840830448, 10.274554794520554, 10.003000000000005, 10.207295918367358], "value": 10.207295918367358, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.256974358974363, 9.711893203883502, 10.417239583333336, 10.103207070707077, 10.31345360824741, 9.901905940594066, 10.104848484848494, 10.05030150753768, 9.66393719806764, 10.000849999999991, 9.56964114832536, 9.760073170731701], "value": 10.000849999999991, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.155855018587358, 11.111444444444432, 11.073690036900373, 11.111055555555545, 11.07053505535056, 11.155390334572488, 11.113981481481487, 10.991318681318685, 11.112907407407409, 11.111555555555551, 11.11151851851852, 11.152639405204452], "value": 11.11151851851852, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.639335106382978, 10.64066489361702, 10.641968085106395, 10.526842105263189, 10.700695187165769, 10.583280423280412, 10.585582010582026, 10.640558510638312, 10.640691489361688, 10.753225806451644, 10.757284946236537, 10.642101063829807], "value": 10.640691489361688, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.398202479338835, 12.297295081967187, 12.298852459016386, 12.606575630252124, 12.29745901639345, 12.55573221757322, 12.503645833333334, 12.500187500000022, 12.50362499999998, 12.347139917695456, 12.503645833333334, 12.7117161016949], "value": 12.50362499999998, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.908690476190497, 11.768147058823567, 11.836183431952676, 12.270950920245404, 11.840059171597597, 12.050150602409621, 11.909285714285726, 11.835946745562095, 12.054457831325307, 11.632383720930257, 12.05403614457832, 12.198841463414638], "value": 11.909285714285726, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.541653846153853, 11.281973684210516, 11.673385214007773, 11.494827586206902, 11.630406976744203, 11.322679245283027, 11.450973282442744, 11.62984496124031, 11.452347328244272, 11.540615384615375, 11.410532319391612, 11.629282945736447], "value": 11.494827586206902, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.116527777777769, 10.754354838709665, 10.989395604395602, 10.929699453551883, 10.81686486486484, 10.932431693989082, 11.0507182320442, 11.240224719101095, 11.053591160220979, 10.993159340659327, 10.990329670329698, 11.241432584269655], "value": 10.990329670329698, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.937950000000001, 6.462750000000001, 6.6103, 6.589449999999999, 6.60215], "value": 6.5958, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}]}
16:27:14 INFO - Return code: 0
16:27:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
16:27:14 INFO - # TBPL SUCCESS #
16:27:14 INFO - Running post-action listener: _package_coverage_data
16:27:14 INFO - Running post-action listener: _resource_record_post_action
16:27:14 INFO - [mozharness: 2017-10-13 23:27:14.667706Z] Finished run-tests step (success)
16:27:14 INFO - Running post-run listener: _resource_record_post_run
16:27:14 INFO - Total resource usage - Wall time: 594s; CPU: 39.0%; Read bytes: 2805760; Write bytes: 319889408; Read time: 144; Write time: 189732
16:27:14 INFO - TinderboxPrint: CPU usage
38.6%
16:27:14 INFO - TinderboxPrint: I/O read bytes / time
2,805,760 / 144
16:27:14 INFO - TinderboxPrint: I/O write bytes / time
319,889,408 / 189,732
16:27:14 INFO - TinderboxPrint: CPU idle
2,861.7 (61.4%)
16:27:14 INFO - TinderboxPrint: CPU system
303.4 (6.5%)
16:27:14 INFO - TinderboxPrint: CPU user
1,484.1 (31.8%)
16:27:14 INFO - TinderboxPrint: Swap in / out
0 / 0
16:27:14 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 13160448; Read time: 0; Write time: 2640
16:27:14 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
16:27:14 INFO - run-tests - Wall time: 576s; CPU: 39.0%; Read bytes: 2801664; Write bytes: 306728960; Read time: 128; Write time: 187092
16:27:14 INFO - Running post-run listener: _upload_blobber_files
16:27:14 INFO - Blob upload gear active.
16:27:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
16:27:14 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
16:27:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
16:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
16:27:15 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:334: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:15 INFO - SNIMissingWarning
16:27:15 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:15 INFO - InsecurePlatformWarning
16:27:15 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:27:15 INFO - SubjectAltNameWarning
16:27:15 INFO - (blobuploader) - INFO - Open directory for files ...
16:27:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
16:27:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:27:15 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:27:15 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:15 INFO - InsecurePlatformWarning
16:27:15 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:27:15 INFO - SubjectAltNameWarning
16:27:16 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:16 INFO - InsecurePlatformWarning
16:27:16 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
16:27:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:27:16 INFO - (blobuploader) - INFO - Done attempting.
16:27:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
16:27:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:27:16 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:27:16 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:16 INFO - InsecurePlatformWarning
16:27:16 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:27:16 INFO - SubjectAltNameWarning
16:27:19 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:19 INFO - InsecurePlatformWarning
16:27:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
16:27:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:27:19 INFO - (blobuploader) - INFO - Done attempting.
16:27:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
16:27:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:27:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:27:19 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:19 INFO - InsecurePlatformWarning
16:27:19 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:27:19 INFO - SubjectAltNameWarning
16:27:19 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:19 INFO - InsecurePlatformWarning
16:27:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
16:27:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:27:20 INFO - (blobuploader) - INFO - Done attempting.
16:27:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
16:27:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:27:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:27:20 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:20 INFO - InsecurePlatformWarning
16:27:20 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:27:20 INFO - SubjectAltNameWarning
16:27:20 INFO - /builds/slave/test/build/venv/local/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:27:20 INFO - InsecurePlatformWarning
16:27:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
16:27:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:27:21 INFO - (blobuploader) - INFO - Done attempting.
16:27:21 INFO - (blobuploader) - INFO - Iteration through files over.
16:27:21 INFO - Return code: 0
16:27:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
16:27:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
16:27:21 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03556e97ea5b776b53ac9e4c7da10bb6d826a7e5c3cd00155203886a8684b20a873ab1efabeb0fbecb94459798f01f20b7a3c41830dc18d6781919cb7862399f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e02e47e7d00c62b7c9e653a70d391821451a4f466f950faea21567924e0d3e7258b34272c8e7410bedf5ec7634cc71fdaf4462e0d7587099c5403f46ed9b97fa", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f90acdffaac175077fd4ca692470f9b35637c94d24cc1aea74b856fe49d2e4051a51aeac60af59720310db2b1aa5ec0c2614e96d51368537623fcc7ac1122152", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cbebbf1627119c564d5d32c0611a98f189bb47108f8b654b3713860f4a5e5f01d43cf2b5b077420c2f683929bf748db545f99d36cb8cd76dfbcfeaec7b6e7ae"}
16:27:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
16:27:21 INFO - Writing to file /builds/slave/test/properties/blobber_files
16:27:21 INFO - Contents:
16:27:21 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03556e97ea5b776b53ac9e4c7da10bb6d826a7e5c3cd00155203886a8684b20a873ab1efabeb0fbecb94459798f01f20b7a3c41830dc18d6781919cb7862399f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e02e47e7d00c62b7c9e653a70d391821451a4f466f950faea21567924e0d3e7258b34272c8e7410bedf5ec7634cc71fdaf4462e0d7587099c5403f46ed9b97fa", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f90acdffaac175077fd4ca692470f9b35637c94d24cc1aea74b856fe49d2e4051a51aeac60af59720310db2b1aa5ec0c2614e96d51368537623fcc7ac1122152", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cbebbf1627119c564d5d32c0611a98f189bb47108f8b654b3713860f4a5e5f01d43cf2b5b077420c2f683929bf748db545f99d36cb8cd76dfbcfeaec7b6e7ae"}
16:27:21 INFO - Running post-run listener: copy_logs_to_upload_dir
16:27:21 INFO - Copying logs to upload dir...
16:27:21 INFO - mkdir: /builds/slave/test/build/upload/logs
16:27:21 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=643.402767
========= master_lag: 0.11 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 43 secs) (at 2017-10-13 16:27:20.869472) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-13 16:27:20.873789) =========
bash -c 'for file in `ls -1`; do cat $file; done'
in dir /builds/slave/test/properties (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test/properties
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03556e97ea5b776b53ac9e4c7da10bb6d826a7e5c3cd00155203886a8684b20a873ab1efabeb0fbecb94459798f01f20b7a3c41830dc18d6781919cb7862399f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e02e47e7d00c62b7c9e653a70d391821451a4f466f950faea21567924e0d3e7258b34272c8e7410bedf5ec7634cc71fdaf4462e0d7587099c5403f46ed9b97fa", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f90acdffaac175077fd4ca692470f9b35637c94d24cc1aea74b856fe49d2e4051a51aeac60af59720310db2b1aa5ec0c2614e96d51368537623fcc7ac1122152", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cbebbf1627119c564d5d32c0611a98f189bb47108f8b654b3713860f4a5e5f01d43cf2b5b077420c2f683929bf748db545f99d36cb8cd76dfbcfeaec7b6e7ae"}
build_url:https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011402
build_url: 'https://queue.taskcluster.net/v1/task/YvDagPrjRKSfL6iYEaFMgA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03556e97ea5b776b53ac9e4c7da10bb6d826a7e5c3cd00155203886a8684b20a873ab1efabeb0fbecb94459798f01f20b7a3c41830dc18d6781919cb7862399f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e02e47e7d00c62b7c9e653a70d391821451a4f466f950faea21567924e0d3e7258b34272c8e7410bedf5ec7634cc71fdaf4462e0d7587099c5403f46ed9b97fa", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f90acdffaac175077fd4ca692470f9b35637c94d24cc1aea74b856fe49d2e4051a51aeac60af59720310db2b1aa5ec0c2614e96d51368537623fcc7ac1122152", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cbebbf1627119c564d5d32c0611a98f189bb47108f8b654b3713860f4a5e5f01d43cf2b5b077420c2f683929bf748db545f99d36cb8cd76dfbcfeaec7b6e7ae"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-13 16:27:20.906187) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:27:20.906542) =========
rm -f oauth.txt
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=8c32c1c60be582960b75045f000001c1-1507936595.691892-1027359764
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004546
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-13 16:27:20.989459) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-13 16:27:20.989767) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-13 16:27:20.990066) =========
========= Total master_lag: 0.28 =========