builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-070
starttime: 1510343829.01
results: success (0)
revision: 6f4d0206b1d160ac95d6bbfd0dda57bc51bde177
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.012353) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.013208) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.013533) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.083282) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.083676) =========
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=b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-10 11:57:09-- 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% 24.9M=0s
2017-11-10 11:57:09 (24.9 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.100640
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.205925) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.206514) =========
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=b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.017138
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:09.258999) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-10 11:57:09.259375) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6f4d0206b1d160ac95d6bbfd0dda57bc51bde177 --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 6f4d0206b1d160ac95d6bbfd0dda57bc51bde177 --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=b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-10 11:57:09,312 truncating revision to first 12 chars
2017-11-10 11:57:09,312 Setting DEBUG logging.
2017-11-10 11:57:09,312 attempt 1/10
2017-11-10 11:57:09,313 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6f4d0206b1d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-10 11:57:10,364 unpacking tar archive at: mozilla-central-6f4d0206b1d1/testing/mozharness/
program finished with exit code 0
elapsedTime=1.697683
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-10 11:57:10.975501) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:10.975839) =========
script_repo_revision: 6f4d0206b1d160ac95d6bbfd0dda57bc51bde177
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:10.976261) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:10.976592) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-10 11:57:10.991702) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 36 secs) (at 2017-11-10 11:57:10.992069) =========
/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=b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
11:57:11 INFO - MultiFileLogger online at 20171110 11:57:11 in /builds/slave/test
11:57:11 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
11:57:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
11:57:11 INFO - {'append_to_log': False,
11:57:11 INFO - 'base_work_dir': '/builds/slave/test',
11:57:11 INFO - 'blob_upload_branch': 'Firefox',
11:57:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
11:57:11 INFO - 'branch': 'Firefox',
11:57:11 INFO - 'buildbot_json_path': 'buildprops.json',
11:57:11 INFO - 'code_coverage': False,
11:57:11 INFO - 'config_files': ('talos/linux_config.py',),
11:57:11 INFO - 'default_actions': ('clobber',
11:57:11 INFO - 'read-buildbot-config',
11:57:11 INFO - 'download-and-extract',
11:57:11 INFO - 'populate-webroot',
11:57:11 INFO - 'create-virtualenv',
11:57:11 INFO - 'install',
11:57:11 INFO - 'setup-mitmproxy',
11:57:11 INFO - 'run-tests'),
11:57:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
11:57:11 INFO - 'disable_ccov_upload': False,
11:57:11 INFO - 'disable_stylo': False,
11:57:11 INFO - 'download_minidump_stackwalk': True,
11:57:11 INFO - 'download_symbols': 'ondemand',
11:57:11 INFO - 'e10s': False,
11:57:11 INFO - 'enable_stylo': False,
11:57:11 INFO - 'enable_webrender': False,
11:57:11 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
11:57:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
11:57:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
11:57:11 INFO - 'gecko_profile': False,
11:57:11 INFO - 'gecko_profile_interval': 0,
11:57:11 INFO - 'installer_path': 'installer.exe',
11:57:11 INFO - 'jsd_code_coverage': False,
11:57:11 INFO - 'log_level': 'info',
11:57:11 INFO - 'log_name': 'talos',
11:57:11 INFO - 'log_to_console': True,
11:57:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
11:57:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
11:57:11 INFO - 'opt_config_files': (),
11:57:11 INFO - 'pip_index': False,
11:57:11 INFO - 'suite': 'g4-e10s',
11:57:11 INFO - 'system_bits': '32',
11:57:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
11:57:11 INFO - 'title': 'talos-linux64-ix-070',
11:57:11 INFO - 'tooltool_cache': '/builds/tooltool_cache',
11:57:11 INFO - 'use_talos_json': True,
11:57:11 INFO - 'verify': 'False',
11:57:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
11:57:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
11:57:11 INFO - 'work_dir': 'build'}
11:57:11 INFO - [mozharness: 2017-11-10 19:57:11.181250Z] Running clobber step.
11:57:11 INFO - Running pre-action listener: _resource_record_pre_action
11:57:11 INFO - Running main action method: clobber
11:57:11 INFO - rmtree: /builds/slave/test/build
11:57:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
11:57:12 INFO - Running post-action listener: _resource_record_post_action
11:57:12 INFO - [mozharness: 2017-11-10 19:57:12.764309Z] Finished clobber step (success)
11:57:12 INFO - [mozharness: 2017-11-10 19:57:12.764405Z] Running read-buildbot-config step.
11:57:12 INFO - Running pre-action listener: _resource_record_pre_action
11:57:12 INFO - Running main action method: read_buildbot_config
11:57:12 INFO - Using buildbot properties:
11:57:12 INFO - {
11:57:12 INFO - "project": "",
11:57:12 INFO - "product": "firefox",
11:57:12 INFO - "who": "ffxbld@noreply.mozilla.org",
11:57:12 INFO - "installer_path": "public/build/target.tar.bz2",
11:57:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
11:57:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
11:57:12 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
11:57:12 INFO - "stage_platform": "linux64-qr",
11:57:12 INFO - "basedir": "/builds/slave/test",
11:57:12 INFO - "buildnumber": 86,
11:57:12 INFO - "platform": "ubuntu64_hw_qr",
11:57:12 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
11:57:12 INFO - "slavebuilddir": "test",
11:57:12 INFO - "taskId": "Uuw0QQ0DQCSqBFNzL9DJtA",
11:57:12 INFO - "branch": "mozilla-central",
11:57:12 INFO - "script_repo_revision": "production",
11:57:12 INFO - "revision": "6f4d0206b1d160ac95d6bbfd0dda57bc51bde177",
11:57:12 INFO - "slavename": "talos-linux64-ix-070",
11:57:12 INFO - "repo_path": "mozilla-central"
11:57:12 INFO - }
11:57:12 INFO - Finding installer, test and symbols from parent task.
11:57:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uuw0QQ0DQCSqBFNzL9DJtA'}, attempt #1
11:57:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uuw0QQ0DQCSqBFNzL9DJtA'}, attempt #1
11:57:13 INFO - Task dependencies: EXw57MOCS5iSEAib7P4deg
11:57:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg'}, attempt #1
11:57:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2
11:57:14 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.test_packages.json
11:57:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.crashreporter-symbols.zip
11:57:14 INFO - Running post-action listener: _resource_record_post_action
11:57:14 INFO - [mozharness: 2017-11-10 19:57:14.106215Z] Finished read-buildbot-config step (success)
11:57:14 INFO - [mozharness: 2017-11-10 19:57:14.106555Z] Running download-and-extract step.
11:57:14 INFO - Running pre-action listener: _resource_record_pre_action
11:57:14 INFO - Running main action method: download_and_extract
11:57:14 INFO - mkdir: /builds/slave/test/build/tests
11:57:14 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')]}
11:57:14 INFO - trying https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.test_packages.json
11:57:14 INFO - Downloading https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
11:57:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
11:57:14 INFO - Downloaded 989 bytes.
11:57:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
11:57:14 INFO - Using the following test package requirements:
11:57:14 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
11:57:14 INFO - u'common': [u'target.common.tests.zip'],
11:57:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
11:57:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
11:57:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
11:57:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
11:57:14 INFO - u'mozbase': [u'target.common.tests.zip'],
11:57:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
11:57:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
11:57:14 INFO - u'web-platform': [u'target.common.tests.zip',
11:57:14 INFO - u'target.web-platform.tests.tar.gz'],
11:57:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
11:57:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
11:57:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.common.tests.zip
11:57:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.common.tests.zip'}, attempt #1
11:57:14 INFO - Fetch https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.common.tests.zip into memory
11:57:16 INFO - Content-Length response header: 57791741
11:57:16 INFO - Bytes received: 57791741
11:57:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.talos.tests.zip
11:57:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.talos.tests.zip'}, attempt #1
11:57:26 INFO - Fetch https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.talos.tests.zip into memory
11:57:27 INFO - Content-Length response header: 18771918
11:57:27 INFO - Bytes received: 18771918
11:57:28 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')]}
11:57:28 INFO - trying https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2
11:57:28 INFO - Downloading https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
11:57:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
11:57:30 INFO - Downloaded 62485362 bytes.
11:57:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2
11:57:30 INFO - mkdir: /builds/slave/test/properties
11:57:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
11:57:30 INFO - Writing to file /builds/slave/test/properties/build_url
11:57:30 INFO - Contents:
11:57:30 INFO - build_url:https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2
11:57:30 INFO - Running post-action listener: _resource_record_post_action
11:57:30 INFO - Running post-action listener: find_tests_for_verification
11:57:30 INFO - Running post-action listener: set_extra_try_arguments
11:57:30 INFO - [mozharness: 2017-11-10 19:57:30.051044Z] Finished download-and-extract step (success)
11:57:30 INFO - [mozharness: 2017-11-10 19:57:30.051215Z] Running populate-webroot step.
11:57:30 INFO - Running pre-action listener: _resource_record_pre_action
11:57:30 INFO - Running main action method: populate_webroot
11:57:30 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
11:57:30 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['tresize',
11:57:30 INFO - 'tcanvasmark']},
11:57:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
11:57:30 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['dromaeo_css',
11:57:30 INFO - 'kraken']},
11:57:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
11:57:30 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
11:57:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'tests': ['damp', 'tps']},
11:57:30 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['damp', 'tps']},
11:57:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
11:57:30 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['dromaeo_dom']},
11:57:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
11:57:30 INFO - 'glvideo',
11:57:30 INFO - 'displaylist_mutate']},
11:57:30 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['basic_compositor_video',
11:57:30 INFO - 'glvideo']},
11:57:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
11:57:30 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['ts_paint_webext',
11:57:30 INFO - 'tp5o_webext']},
11:57:30 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
11:57:30 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:57:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:57:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:57:30 INFO - 'talos_options': ['--mitmproxy',
11:57:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:57:30 INFO - '--firstNonBlankPaint'],
11:57:30 INFO - 'tests': ['tp6_google_heavy',
11:57:30 INFO - 'tp6_youtube_heavy',
11:57:30 INFO - 'tp6_amazon_heavy',
11:57:30 INFO - 'tp6_facebook_heavy']},
11:57:30 INFO - 'other-e10s': {'tests': ['a11yr',
11:57:30 INFO - 'ts_paint',
11:57:30 INFO - 'tpaint',
11:57:30 INFO - 'sessionrestore',
11:57:30 INFO - 'sessionrestore_many_windows',
11:57:30 INFO - 'sessionrestore_no_auto_restore',
11:57:30 INFO - 'tabpaint',
11:57:30 INFO - 'cpstartup']},
11:57:30 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['a11yr',
11:57:30 INFO - 'ts_paint',
11:57:30 INFO - 'tpaint',
11:57:30 INFO - 'sessionrestore',
11:57:30 INFO - 'sessionrestore_many_windows',
11:57:30 INFO - 'sessionrestore_no_auto_restore',
11:57:30 INFO - 'tabpaint',
11:57:30 INFO - 'cpstartup']},
11:57:30 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
11:57:30 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
11:57:30 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['perf_reftest_singletons']},
11:57:30 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['perf_reftest']},
11:57:30 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
11:57:30 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['speedometer']},
11:57:30 INFO - 'svgr-e10s': {'tests': ['tsvgx',
11:57:30 INFO - 'tsvgr_opacity',
11:57:30 INFO - 'tart',
11:57:30 INFO - 'tscrollx',
11:57:30 INFO - 'tsvg_static']},
11:57:30 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['tsvgx',
11:57:30 INFO - 'tsvgr_opacity',
11:57:30 INFO - 'tart',
11:57:30 INFO - 'tscrollx',
11:57:30 INFO - 'tsvg_static']},
11:57:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
11:57:30 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'talos_options': ['--disable-stylo'],
11:57:30 INFO - 'tests': ['tp5o']},
11:57:30 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:57:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:57:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:57:30 INFO - 'talos_options': ['--mitmproxy',
11:57:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:57:30 INFO - '--firstNonBlankPaint'],
11:57:30 INFO - 'tests': ['tp6_google',
11:57:30 INFO - 'tp6_youtube',
11:57:30 INFO - 'tp6_amazon',
11:57:30 INFO - 'tp6_facebook']},
11:57:30 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:57:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:57:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:57:30 INFO - 'talos_options': ['--disable-stylo',
11:57:30 INFO - '--mitmproxy',
11:57:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:57:30 INFO - '--firstNonBlankPaint'],
11:57:30 INFO - 'tests': ['tp6_google',
11:57:30 INFO - 'tp6_youtube',
11:57:30 INFO - 'tp6_amazon',
11:57:30 INFO - 'tp6_facebook']},
11:57:30 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:57:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:57:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:57:30 INFO - 'talos_options': ['--stylo-threads=1',
11:57:30 INFO - '--mitmproxy',
11:57:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:57:30 INFO - '--firstNonBlankPaint'],
11:57:30 INFO - 'tests': ['tp6_google',
11:57:30 INFO - 'tp6_youtube',
11:57:30 INFO - 'tp6_amazon',
11:57:30 INFO - 'tp6_facebook']},
11:57:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'talos_options': ['--xperf_path',
11:57:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
11:57:30 INFO - 'tests': ['tp5n']},
11:57:30 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:57:30 INFO - 'talos_options': ['--disable-stylo',
11:57:30 INFO - '--xperf_path',
11:57:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
11:57:30 INFO - 'tests': ['tp5n']}}}
11:57:30 INFO - Running post-action listener: _resource_record_post_action
11:57:30 INFO - [mozharness: 2017-11-10 19:57:30.069558Z] Finished populate-webroot step (success)
11:57:30 INFO - [mozharness: 2017-11-10 19:57:30.069650Z] Running create-virtualenv step.
11:57:30 INFO - Running pre-action listener: _resource_record_pre_action
11:57:30 INFO - Running main action method: create_virtualenv
11:57:30 INFO - Creating virtualenv /builds/slave/test/build/venv
11:57:30 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
11:57:30 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
11:57:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
11:57:30 INFO - Using real prefix '/usr'
11:57:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python
11:57:30 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
11:57:32 INFO - Installing setuptools, pip, wheel...done.
11:57:32 INFO - Return code: 0
11:57:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
11:57:32 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')]}
11:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57:32 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')]}
11:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:32 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:32 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
11:57:32 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
11:57:32 INFO - Using env: {'DISPLAY': ':0',
11:57:32 INFO - 'HOME': '/home/cltbld',
11:57:32 INFO - 'LANG': 'en_US.UTF-8',
11:57:32 INFO - 'LANGUAGE': 'en_US:en',
11:57:32 INFO - 'LOGNAME': 'cltbld',
11:57:32 INFO - 'MAIL': '/var/mail/cltbld',
11:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:32 INFO - 'MOZ_NO_REMOTE': '1',
11:57:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:32 INFO - 'NO_EM_RESTART': '1',
11:57:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:32 INFO - 'PWD': '/builds/slave/test',
11:57:32 INFO - 'SHELL': '/bin/bash',
11:57:32 INFO - 'SHLVL': '1',
11:57:32 INFO - 'TERM': 'linux',
11:57:32 INFO - 'TMOUT': '86400',
11:57:32 INFO - 'USER': 'cltbld',
11:57:32 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:32 INFO - '_': '/tools/buildbot/bin/python'}
11:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
11:57:32 INFO - Return code: 0
11:57:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
11:57:32 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')]}
11:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57:32 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')]}
11:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:32 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:32 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
11:57:32 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
11:57:32 INFO - Using env: {'DISPLAY': ':0',
11:57:32 INFO - 'HOME': '/home/cltbld',
11:57:32 INFO - 'LANG': 'en_US.UTF-8',
11:57:32 INFO - 'LANGUAGE': 'en_US:en',
11:57:32 INFO - 'LOGNAME': 'cltbld',
11:57:32 INFO - 'MAIL': '/var/mail/cltbld',
11:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:32 INFO - 'MOZ_NO_REMOTE': '1',
11:57:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:32 INFO - 'NO_EM_RESTART': '1',
11:57:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:32 INFO - 'PWD': '/builds/slave/test',
11:57:32 INFO - 'SHELL': '/bin/bash',
11:57:32 INFO - 'SHLVL': '1',
11:57:32 INFO - 'TERM': 'linux',
11:57:32 INFO - 'TMOUT': '86400',
11:57:32 INFO - 'USER': 'cltbld',
11:57:32 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:32 INFO - '_': '/tools/buildbot/bin/python'}
11:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:33 INFO - Collecting psutil>=3.1.1
11:57:34 INFO - Installing collected packages: psutil
11:57:34 INFO - Successfully installed psutil-3.1.1
11:57:34 INFO - Return code: 0
11:57:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
11:57:34 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')]}
11:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57:34 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')]}
11:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:34 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:34 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
11:57:34 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
11:57:34 INFO - Using env: {'DISPLAY': ':0',
11:57:34 INFO - 'HOME': '/home/cltbld',
11:57:34 INFO - 'LANG': 'en_US.UTF-8',
11:57:34 INFO - 'LANGUAGE': 'en_US:en',
11:57:34 INFO - 'LOGNAME': 'cltbld',
11:57:34 INFO - 'MAIL': '/var/mail/cltbld',
11:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:34 INFO - 'MOZ_NO_REMOTE': '1',
11:57:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:34 INFO - 'NO_EM_RESTART': '1',
11:57:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:34 INFO - 'PWD': '/builds/slave/test',
11:57:34 INFO - 'SHELL': '/bin/bash',
11:57:34 INFO - 'SHLVL': '1',
11:57:34 INFO - 'TERM': 'linux',
11:57:34 INFO - 'TMOUT': '86400',
11:57:34 INFO - 'USER': 'cltbld',
11:57:34 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:34 INFO - '_': '/tools/buildbot/bin/python'}
11:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:34 INFO - Collecting mozsystemmonitor==0.3
11:57:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
11:57:35 INFO - Installing collected packages: mozsystemmonitor
11:57:35 INFO - Successfully installed mozsystemmonitor-0.3
11:57:35 INFO - Return code: 0
11:57:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
11:57:35 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')]}
11:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57:35 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')]}
11:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:35 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:35 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
11:57:35 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
11:57:35 INFO - Using env: {'DISPLAY': ':0',
11:57:35 INFO - 'HOME': '/home/cltbld',
11:57:35 INFO - 'LANG': 'en_US.UTF-8',
11:57:35 INFO - 'LANGUAGE': 'en_US:en',
11:57:35 INFO - 'LOGNAME': 'cltbld',
11:57:35 INFO - 'MAIL': '/var/mail/cltbld',
11:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:35 INFO - 'MOZ_NO_REMOTE': '1',
11:57:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:35 INFO - 'NO_EM_RESTART': '1',
11:57:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:35 INFO - 'PWD': '/builds/slave/test',
11:57:35 INFO - 'SHELL': '/bin/bash',
11:57:35 INFO - 'SHLVL': '1',
11:57:35 INFO - 'TERM': 'linux',
11:57:35 INFO - 'TMOUT': '86400',
11:57:35 INFO - 'USER': 'cltbld',
11:57:35 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:35 INFO - '_': '/tools/buildbot/bin/python'}
11:57:36 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:36 INFO - Collecting jsonschema==2.5.1
11:57:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
11:57:37 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
11:57:38 INFO - Installing collected packages: functools32, jsonschema
11:57:38 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
11:57:38 INFO - Return code: 0
11:57:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
11:57: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')]}
11:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57: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')]}
11:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:38 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:38 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
11:57:38 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
11:57:38 INFO - Using env: {'DISPLAY': ':0',
11:57:38 INFO - 'HOME': '/home/cltbld',
11:57:38 INFO - 'LANG': 'en_US.UTF-8',
11:57:38 INFO - 'LANGUAGE': 'en_US:en',
11:57:38 INFO - 'LOGNAME': 'cltbld',
11:57:38 INFO - 'MAIL': '/var/mail/cltbld',
11:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:38 INFO - 'MOZ_NO_REMOTE': '1',
11:57:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:38 INFO - 'NO_EM_RESTART': '1',
11:57:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:38 INFO - 'PWD': '/builds/slave/test',
11:57:38 INFO - 'SHELL': '/bin/bash',
11:57:38 INFO - 'SHLVL': '1',
11:57:38 INFO - 'TERM': 'linux',
11:57:38 INFO - 'TMOUT': '86400',
11:57:38 INFO - 'USER': 'cltbld',
11:57:38 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:38 INFO - '_': '/tools/buildbot/bin/python'}
11:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
11:57:38 INFO - Return code: 0
11:57:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
11:57: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')]}
11:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57: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')]}
11:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:38 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:38 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
11:57:38 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
11:57:38 INFO - Using env: {'DISPLAY': ':0',
11:57:38 INFO - 'HOME': '/home/cltbld',
11:57:38 INFO - 'LANG': 'en_US.UTF-8',
11:57:38 INFO - 'LANGUAGE': 'en_US:en',
11:57:38 INFO - 'LOGNAME': 'cltbld',
11:57:38 INFO - 'MAIL': '/var/mail/cltbld',
11:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:38 INFO - 'MOZ_NO_REMOTE': '1',
11:57:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:38 INFO - 'NO_EM_RESTART': '1',
11:57:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:38 INFO - 'PWD': '/builds/slave/test',
11:57:38 INFO - 'SHELL': '/bin/bash',
11:57:38 INFO - 'SHLVL': '1',
11:57:38 INFO - 'TERM': 'linux',
11:57:38 INFO - 'TMOUT': '86400',
11:57:38 INFO - 'USER': 'cltbld',
11:57:38 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:38 INFO - '_': '/tools/buildbot/bin/python'}
11:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:39 INFO - Collecting blobuploader==1.2.4
11:57:40 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
11:57:41 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
11:57:42 INFO - Installing collected packages: requests, docopt, blobuploader
11:57:42 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
11:57:42 INFO - Return code: 0
11:57:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:57: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')]}
11:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57: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')]}
11:57:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:42 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:42 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
11:57:42 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
11:57:42 INFO - Using env: {'DISPLAY': ':0',
11:57:42 INFO - 'HOME': '/home/cltbld',
11:57:42 INFO - 'LANG': 'en_US.UTF-8',
11:57:42 INFO - 'LANGUAGE': 'en_US:en',
11:57:42 INFO - 'LOGNAME': 'cltbld',
11:57:42 INFO - 'MAIL': '/var/mail/cltbld',
11:57:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:42 INFO - 'MOZ_NO_REMOTE': '1',
11:57:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:42 INFO - 'NO_EM_RESTART': '1',
11:57:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:42 INFO - 'PWD': '/builds/slave/test',
11:57:42 INFO - 'SHELL': '/bin/bash',
11:57:42 INFO - 'SHLVL': '1',
11:57:42 INFO - 'TERM': 'linux',
11:57:42 INFO - 'TMOUT': '86400',
11:57:42 INFO - 'USER': 'cltbld',
11:57:42 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:42 INFO - '_': '/tools/buildbot/bin/python'}
11:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:43 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
11:57:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
11:57:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
11:57:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
11:57:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
11:57:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
11:57:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
11:57:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
11:57:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
11:57:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
11:57:45 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
11:57:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
11:57:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
11:57:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
11:57:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
11:57:46 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
11:57:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
11:57:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
11:57:46 INFO - Running setup.py install for manifestparser: started
11:57:46 INFO - Running setup.py install for manifestparser: finished with status 'done'
11:57:46 INFO - Running setup.py install for mozcrash: started
11:57:47 INFO - Running setup.py install for mozcrash: finished with status 'done'
11:57:47 INFO - Running setup.py install for mozdebug: started
11:57:47 INFO - Running setup.py install for mozdebug: finished with status 'done'
11:57:47 INFO - Running setup.py install for mozdevice: started
11:57:47 INFO - Running setup.py install for mozdevice: finished with status 'done'
11:57:47 INFO - Running setup.py install for mozfile: started
11:57:47 INFO - Running setup.py install for mozfile: finished with status 'done'
11:57:47 INFO - Running setup.py install for mozhttpd: started
11:57:47 INFO - Running setup.py install for mozhttpd: finished with status 'done'
11:57:47 INFO - Running setup.py install for mozinfo: started
11:57:48 INFO - Running setup.py install for mozinfo: finished with status 'done'
11:57:48 INFO - Running setup.py install for mozInstall: started
11:57:48 INFO - Running setup.py install for mozInstall: finished with status 'done'
11:57:48 INFO - Running setup.py install for mozleak: started
11:57:48 INFO - Running setup.py install for mozleak: finished with status 'done'
11:57:48 INFO - Running setup.py install for mozlog: started
11:57:48 INFO - Running setup.py install for mozlog: finished with status 'done'
11:57:48 INFO - Running setup.py install for moznetwork: started
11:57:48 INFO - Running setup.py install for moznetwork: finished with status 'done'
11:57:48 INFO - Running setup.py install for mozprocess: started
11:57:49 INFO - Running setup.py install for mozprocess: finished with status 'done'
11:57:49 INFO - Running setup.py install for mozprofile: started
11:57:49 INFO - Running setup.py install for mozprofile: finished with status 'done'
11:57:49 INFO - Running setup.py install for mozrunner: started
11:57:49 INFO - Running setup.py install for mozrunner: finished with status 'done'
11:57:49 INFO - Running setup.py install for mozscreenshot: started
11:57:49 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
11:57:49 INFO - Running setup.py install for moztest: started
11:57:50 INFO - Running setup.py install for moztest: finished with status 'done'
11:57:50 INFO - Running setup.py install for mozversion: started
11:57:50 INFO - Running setup.py install for mozversion: finished with status 'done'
11:57:50 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
11:57:50 INFO - Return code: 0
11:57:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:57:50 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')]}
11:57:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57:50 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')]}
11:57:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:50 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:50 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
11:57:50 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
11:57:50 INFO - Using env: {'DISPLAY': ':0',
11:57:50 INFO - 'HOME': '/home/cltbld',
11:57:50 INFO - 'LANG': 'en_US.UTF-8',
11:57:50 INFO - 'LANGUAGE': 'en_US:en',
11:57:50 INFO - 'LOGNAME': 'cltbld',
11:57:50 INFO - 'MAIL': '/var/mail/cltbld',
11:57:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:50 INFO - 'MOZ_NO_REMOTE': '1',
11:57:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:50 INFO - 'NO_EM_RESTART': '1',
11:57:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:50 INFO - 'PWD': '/builds/slave/test',
11:57:50 INFO - 'SHELL': '/bin/bash',
11:57:50 INFO - 'SHLVL': '1',
11:57:50 INFO - 'TERM': 'linux',
11:57:50 INFO - 'TMOUT': '86400',
11:57:50 INFO - 'USER': 'cltbld',
11:57:50 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:50 INFO - '_': '/tools/buildbot/bin/python'}
11:57:51 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:51 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
11:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 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))
11:57:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
11:57:51 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))
11:57:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
11:57:51 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))
11:57:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
11:57:51 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))
11:57:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
11:57:52 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))
11:57:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
11:57:52 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))
11:57:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
11:57:52 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))
11:57:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
11:57:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 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))
11:57:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
11:57:52 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))
11:57:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
11:57:53 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))
11:57:53 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
11:57:53 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))
11:57:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
11:57:53 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))
11:57:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
11:57:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 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))
11:57:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
11:57:53 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))
11:57:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
11:57:54 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))
11:57:54 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
11:57:54 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))
11:57:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
11:57:54 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))
11:57:54 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
11:57:55 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
11:57:55 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))
11:57:55 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))
11:57:55 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))
11:57:55 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))
11:57:55 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))
11:57:55 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
11:57:55 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
11:57:56 INFO - Installing collected packages: six, blessings
11:57:56 INFO - Successfully installed blessings-1.6 six-1.10.0
11:57:56 INFO - Return code: 0
11:57:56 INFO - Done creating virtualenv /builds/slave/test/build/venv.
11:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
11:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
11:57:57 INFO - Reading from file tmpfile_stdout
11:57:57 INFO - Reading from file tmpfile_stderr
11:57:57 INFO - Current package versions:
11:57:57 INFO - blessings == 1.6
11:57:57 INFO - blobuploader == 1.2.4
11:57:57 INFO - docopt == 0.6.1
11:57:57 INFO - functools32 == 3.2.3.post2
11:57:57 INFO - jsonschema == 2.5.1
11:57:57 INFO - manifestparser == 1.2
11:57:57 INFO - mozInstall == 1.14
11:57:57 INFO - mozcrash == 1.0
11:57:57 INFO - mozdebug == 0.1
11:57:57 INFO - mozdevice == 0.51
11:57:57 INFO - mozfile == 1.2
11:57:57 INFO - mozhttpd == 0.7
11:57:57 INFO - mozinfo == 0.10
11:57:57 INFO - mozleak == 0.1
11:57:57 INFO - mozlog == 3.5
11:57:57 INFO - moznetwork == 0.27
11:57:57 INFO - mozprocess == 0.25
11:57:57 INFO - mozprofile == 0.29
11:57:57 INFO - mozrunner == 6.13
11:57:57 INFO - mozscreenshot == 0.1
11:57:57 INFO - mozsystemmonitor == 0.3
11:57:57 INFO - moztest == 0.8
11:57:57 INFO - mozversion == 1.4
11:57:57 INFO - psutil == 3.1.1
11:57:57 INFO - requests == 1.2.3
11:57:57 INFO - six == 1.10.0
11:57:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:57: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')]}
11:57:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:57: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')]}
11:57:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:57:57 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:57:57 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
11:57:57 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
11:57:57 INFO - Using env: {'DISPLAY': ':0',
11:57:57 INFO - 'HOME': '/home/cltbld',
11:57:57 INFO - 'LANG': 'en_US.UTF-8',
11:57:57 INFO - 'LANGUAGE': 'en_US:en',
11:57:57 INFO - 'LOGNAME': 'cltbld',
11:57:57 INFO - 'MAIL': '/var/mail/cltbld',
11:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:57:57 INFO - 'MOZ_NO_REMOTE': '1',
11:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:57:57 INFO - 'NO_EM_RESTART': '1',
11:57:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:57:57 INFO - 'PWD': '/builds/slave/test',
11:57:57 INFO - 'SHELL': '/bin/bash',
11:57:57 INFO - 'SHLVL': '1',
11:57:57 INFO - 'TERM': 'linux',
11:57:57 INFO - 'TMOUT': '86400',
11:57:57 INFO - 'USER': 'cltbld',
11:57:57 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:57:57 INFO - '_': '/tools/buildbot/bin/python'}
11:57:57 INFO - Ignoring indexes: https://pypi.python.org/simple
11:57:57 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))
11:57:57 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))
11:57:57 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))
11:57:57 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))
11:57:57 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))
11:57:57 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))
11:57:57 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))
11:57:57 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))
11:57:57 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))
11:57:57 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
11:57:58 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:57:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
11:57:59 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))
11:57:59 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))
11:57:59 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
11:58:00 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:58:01 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
11:58:01 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:58:02 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
11:58:02 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:58:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
11:58:03 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:58:04 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
11:58:04 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
11:58:04 INFO - Found existing installation: requests 1.2.3
11:58:04 INFO - Uninstalling requests-1.2.3:
11:58:04 INFO - Successfully uninstalled requests-1.2.3
11:58:04 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
11:58:04 INFO - Return code: 0
11:58:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
11:58:04 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')]}
11:58:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:58:04 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')]}
11:58:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:58:04 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd32d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eada80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x204cd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, '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': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139', '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
11:58:04 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', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
11:58:04 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 --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
11:58:04 INFO - Using env: {'DISPLAY': ':0',
11:58:04 INFO - 'HOME': '/home/cltbld',
11:58:04 INFO - 'LANG': 'en_US.UTF-8',
11:58:04 INFO - 'LANGUAGE': 'en_US:en',
11:58:04 INFO - 'LOGNAME': 'cltbld',
11:58:04 INFO - 'MAIL': '/var/mail/cltbld',
11:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:58:04 INFO - 'MOZ_NO_REMOTE': '1',
11:58:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:58:04 INFO - 'NO_EM_RESTART': '1',
11:58:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:58:04 INFO - 'PWD': '/builds/slave/test',
11:58:04 INFO - 'SHELL': '/bin/bash',
11:58:04 INFO - 'SHLVL': '1',
11:58:04 INFO - 'TERM': 'linux',
11:58:04 INFO - 'TMOUT': '86400',
11:58:04 INFO - 'USER': 'cltbld',
11:58:04 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:58:04 INFO - '_': '/tools/buildbot/bin/python'}
11:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple
11:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
11:58:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
11:58:05 INFO - Return code: 0
11:58:05 INFO - Running post-action listener: _resource_record_post_action
11:58:05 INFO - Running post-action listener: _start_resource_monitoring
11:58:05 INFO - Starting resource monitoring.
11:58:05 INFO - [mozharness: 2017-11-10 19:58:05.431638Z] Finished create-virtualenv step (success)
11:58:05 INFO - [mozharness: 2017-11-10 19:58:05.432140Z] Running install step.
11:58:05 INFO - Running pre-action listener: _resource_record_pre_action
11:58:05 INFO - Running main action method: install
11:58:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
11:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
11:58:05 INFO - Reading from file tmpfile_stdout
11:58:05 INFO - Reading from file tmpfile_stderr
11:58:05 INFO - Detecting whether we're running mozinstall >=1.0...
11:58:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
11:58:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
11:58:06 INFO - Reading from file tmpfile_stdout
11:58:06 INFO - Output received:
11:58:06 INFO - Usage: mozinstall [options] installer
11:58:06 INFO - Options:
11:58:06 INFO - -h, --help show this help message and exit
11:58:06 INFO - -d DEST, --destination=DEST
11:58:06 INFO - Directory to install application into. [default:
11:58:06 INFO - "/builds/slave/test"]
11:58:06 INFO - --app=APP Application being installed. [default: firefox]
11:58:06 INFO - mkdir: /builds/slave/test/build/application
11:58:06 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']
11:58:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
11:58:24 INFO - Reading from file tmpfile_stdout
11:58:24 INFO - Output received:
11:58:24 INFO - /builds/slave/test/build/application/firefox/firefox
11:58:24 INFO - Running post-action listener: _resource_record_post_action
11:58:24 INFO - [mozharness: 2017-11-10 19:58:24.377238Z] Finished install step (success)
11:58:24 INFO - [mozharness: 2017-11-10 19:58:24.377480Z] Running setup-mitmproxy step.
11:58:24 INFO - Running pre-action listener: _resource_record_pre_action
11:58:24 INFO - Running main action method: setup_mitmproxy
11:58:24 INFO - Skipping: mitmproxy is not required
11:58:24 INFO - Running post-action listener: _resource_record_post_action
11:58:24 INFO - [mozharness: 2017-11-10 19:58:24.378686Z] Finished setup-mitmproxy step (success)
11:58:24 INFO - [mozharness: 2017-11-10 19:58:24.378903Z] Running run-tests step.
11:58:24 INFO - Running pre-action listener: _resource_record_pre_action
11:58:24 INFO - Running pre-action listener: _set_gcov_prefix
11:58:24 INFO - Running main action method: run_tests
11:58:24 WARNING - Try message not found.
11:58:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
11:58:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
11:58:24 INFO - Python 2.7.3
11:58:24 INFO - Return code: 0
11:58:24 INFO - grabbing minidump binary from tooltool
11:58:24 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')]}
11:58:24 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 0x2050070>, '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 0x2051080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x204b2a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
11:58:24 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
11:58:24 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
11:58:24 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
11:58:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
11:58:24 INFO - Return code: 0
11:58:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
11:58:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
11:58:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
11:58:24 INFO - ENV: RUST_BACKTRACE is now full
11:58:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
11:58:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
11:58:24 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/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-070', '--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
11:58:24 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/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-070 --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
11:58:24 INFO - Using env: {'DISPLAY': ':0',
11:58:24 INFO - 'HOME': '/home/cltbld',
11:58:24 INFO - 'LANG': 'en_US.UTF-8',
11:58:24 INFO - 'LANGUAGE': 'en_US:en',
11:58:24 INFO - 'LOGNAME': 'cltbld',
11:58:24 INFO - 'MAIL': '/var/mail/cltbld',
11:58:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:58:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:58:24 INFO - 'MOZ_ACCELERATED': '1',
11:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:58:24 INFO - 'MOZ_NO_REMOTE': '1',
11:58:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:58:24 INFO - 'MOZ_WEBRENDER': '1',
11:58:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:58:24 INFO - 'NO_EM_RESTART': '1',
11:58:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:58:24 INFO - 'PWD': '/builds/slave/test',
11:58:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:58:24 INFO - 'RUST_BACKTRACE': 'full',
11:58:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:58:24 INFO - 'SHELL': '/bin/bash',
11:58:24 INFO - 'SHLVL': '1',
11:58:24 INFO - 'TERM': 'linux',
11:58:24 INFO - 'TMOUT': '86400',
11:58:24 INFO - 'USER': 'cltbld',
11:58:24 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:58:24 INFO - '_': '/tools/buildbot/bin/python'}
11:58:24 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/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-070', '--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
11:58:24 INFO - mozversion application_buildid: 20171110190707
11:58:24 INFO - mozversion application_changeset: 6f4d0206b1d160ac95d6bbfd0dda57bc51bde177
11:58:24 INFO - mozversion application_display_name: Nightly
11:58:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
11:58:24 INFO - mozversion application_name: Firefox
11:58:24 INFO - mozversion application_remotingname: firefox
11:58:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
11:58:24 INFO - mozversion application_vendor: Mozilla
11:58:24 INFO - mozversion application_version: 58.0a1
11:58:24 INFO - mozversion platform_buildid: 20171110190707
11:58:24 INFO - mozversion platform_changeset: 6f4d0206b1d160ac95d6bbfd0dda57bc51bde177
11:58:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
11:58:24 INFO - mozversion platform_version: 58.0a1
11:58:24 INFO - using testdate: 1510343904
11:58:24 INFO - actual date: 1510343904
11:58:24 INFO - starting webserver on 'localhost:36728'
11:58:24 INFO - SUITE-START | Running 3 tests
11:58:24 INFO - TEST-START | basic_compositor_video
11:58:24 INFO - Initialising browser for basic_compositor_video test...
11:58:24 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36728/getInfo.html -profile /tmp/tmp1xCRsv/profile
11:58:24 INFO - TEST-INFO | started process 13079 (/builds/slave/test/build/application/firefox/firefox http://localhost:36728/getInfo.html)
11:58:30 INFO - TEST-INFO | 13079: exit 0
11:58:30 INFO - Browser initialized.
11:58:30 INFO - Running cycle 1/1 for basic_compositor_video test...
11:58:30 INFO - Using env: {'DISPLAY': ':0',
11:58:30 INFO - 'HOME': '/home/cltbld',
11:58:30 INFO - 'JSGC_DISABLE_POISONING': '1',
11:58:30 INFO - 'LANG': 'en_US.UTF-8',
11:58:30 INFO - 'LANGUAGE': 'en_US:en',
11:58:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
11:58:30 INFO - 'LOGNAME': 'cltbld',
11:58:30 INFO - 'MAIL': '/var/mail/cltbld',
11:58:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:58:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:58:30 INFO - 'MOZ_ACCELERATED': '1',
11:58:30 INFO - 'MOZ_CRASHREPORTER': '1',
11:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:58:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
11:58:30 INFO - 'MOZ_NO_REMOTE': '1',
11:58:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:58:30 INFO - 'MOZ_WEBRENDER': '1',
11:58:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:58:30 INFO - 'NO_EM_RESTART': '1',
11:58:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:58:30 INFO - 'PWD': '/builds/slave/test',
11:58:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:58:30 INFO - 'RUST_BACKTRACE': 'full',
11:58:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:58:30 INFO - 'SHELL': '/bin/bash',
11:58:30 INFO - 'SHLVL': '1',
11:58:30 INFO - 'TERM': 'linux',
11:58:30 INFO - 'TMOUT': '86400',
11:58:30 INFO - 'USER': 'cltbld',
11:58:30 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
11:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:58:30 INFO - '_': '/tools/buildbot/bin/python'}
11:58:30 INFO - TEST-INFO | started process 13363 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1xCRsv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
11:58:31 INFO - PID 13363 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
11:58:31 INFO - PID 13363 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
11:58:31 INFO - PID 13363 |
11:58:31 INFO - PID 13363 | (/builds/slave/test/build/application/firefox/firefox:13425): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:58:31 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.608969565217391 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.591878238341969 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.644008810572688 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6324605263157896 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2328340517241374 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.200952000000002 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.232129760225672 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.203098739495797 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.380350364963505 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.3581372549019575 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.846720516962847 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.8445883777239676 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.336124694376533 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.0678621908127015 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.317975609756104 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.067879858657241 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.389926108374379 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.018649122807026 ms/frame
11:59:17 INFO - PID 13363 |
11:59:17 INFO - PID 13363 | Cycle 1(1): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:00:03 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.61141862489121 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5988246753246758 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.648270079435128 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6358695652173925 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.223340494092374 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2111476725521673 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.645309597523221 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.652883720930228 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.498763118440778 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.436164079822623 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.926847290640396 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.832898550724637 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.317804878048778 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.067491166077733 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.283834951456298 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.0937765957446794 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.302554744525544 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.045545774647879 ms/frame
12:00:03 INFO - PID 13363 |
12:00:03 INFO - PID 13363 | Cycle 1(2): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:00:05 INFO - PID 13363 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:00:50 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6526038903625113 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.587503234152652 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6439559471365635 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.636238471673254 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2086631016042766 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2105858747993605 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.688093749999998 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.59810344827586 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.673224299065425 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.475067114093958 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.808525641025642 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.78454545454546 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.283082524271839 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.045440140845066 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.323378048780485 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.121156583629887 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.3194512195121915 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.068215547703181 ms/frame
12:00:50 INFO - PID 13363 |
12:00:50 INFO - PID 13363 | Cycle 1(3): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:01:36 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6180933682373473 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5885252263906855 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6409991197183094 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6316447368421056 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.21617899249732 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2063221153846166 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.255879432624112 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.2924678111588 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.225302816901409 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.1756054279749435 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.831674718196459 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.807560096153845 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.299270072992701 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.093333333333332 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.3186829268292595 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.095478723404265 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.281783980582527 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.0707067137809005 ms/frame
12:01:36 INFO - PID 13363 |
12:01:36 INFO - PID 13363 | Cycle 1(4): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:02:21 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6135757839721254 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5919494818652846 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6531078691423517 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6389709762532996 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2161521972132907 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2113723916532897 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.694921752738654 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.685222482435598 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.4447703703703665 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.618937644341801 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.847560581583198 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.892078239608804 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.371842751842744 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.069363957597189 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.282390776699032 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.1433214285714195 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.300535279805345 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.117491103202845 ms/frame
12:02:21 INFO - PID 13363 |
12:02:21 INFO - PID 13363 | Cycle 1(5): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:03:07 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.602107545533391 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5875808538163 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6458509700176363 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6249212598425204 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2157609860664524 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2104173354735153 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.6810062402496095 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.69680751173709 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.6887890625000015 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.608548387096776 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.8474555735056555 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.751888361045126 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.299683698296842 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.04241197183098 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.301192214111924 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.043063380281701 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.282245145631077 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.093758865248209 ms/frame
12:03:07 INFO - PID 13363 |
12:03:07 INFO - PID 13363 | Cycle 1(6): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:03:53 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6114360313315927 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.59470168612192 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.63915127528584 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.63522397891963 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.20567841880342 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2062580128205127 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.3166546762589935 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.329902597402599 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.608387096774195 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.7071411764705875 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.832495974235104 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.752244655581947 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.317195121951218 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.0952127659574415 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.301034063260337 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.1177402135231285 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.39068965517242 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.070318021201419 ms/frame
12:03:53 INFO - PID 13363 |
12:03:53 INFO - PID 13363 | Cycle 1(7): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:04:39 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6182417102966844 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.587975420439845 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6414832746478876 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.638845646437996 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2159217577706323 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.215844051446944 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.342547033285093 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.301763440860214 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.643978328173372 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.415827814569534 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.855064724919089 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.809591346153848 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.335281173594135 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.076448763250891 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.283810679611651 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.092765957446795 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.299586374695875 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.020210526315797 ms/frame
12:04:39 INFO - PID 13363 |
12:04:39 INFO - PID 13363 | Cycle 1(8): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:05:25 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6161595466434178 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5979870129870126 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.653015030946065 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6259448818897653 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2095347593582897 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.200672 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.317863309352517 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.246592356687899 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.400095307917891 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.588830275229356 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.83899193548387 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.809963942307691 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.319439024390247 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.017315789473676 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.33621026894866 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.069664310954051 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.3010462287104705 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.095106382978722 ms/frame
12:05:25 INFO - PID 13363 |
12:05:25 INFO - PID 13363 | Cycle 1(9): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:06:11 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.608830434782609 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.59812987012987 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.655446902654867 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6358168642951263 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2125481798715207 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.206338141025643 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.238679378531072 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.1930712788259985 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.267446657183504 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.159823284823285 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.847592891760905 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.809026442307693 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.335550122249392 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.093226950354613 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.248647342995157 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.068180212014154 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.372260442260448 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.068851590106009 ms/frame
12:06:11 INFO - PID 13363 |
12:06:11 INFO - PID 13363 | Cycle 1(10): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:06:57 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6088782608695653 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5908743523316065 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6502738515901063 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6320065789473683 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2231095596133192 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.20551282051282 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.545507575757576 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.526764705882357 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.311235632183907 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.5869839449541265 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.91832786885246 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.87991463414634 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.334975550122252 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.068215547703181 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.2828398058252395 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.070123674911665 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.317329268292691 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.045440140845066 ms/frame
12:06:57 INFO - PID 13363 |
12:06:57 INFO - PID 13363 | Cycle 1(11): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:07:43 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6138240418118466 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.59089378238342 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.643220264317181 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6257874015748035 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2122591006423984 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.206506410256411 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_startup = 4.392825768667642 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1_inclip = 4.367489082969435 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.478104477611938 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.663414918414917 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_startup = 4.848093699515349 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.480p.60fps.webm_scale_2_inclip = 4.808485576923072 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.301119221411188 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.067455830388706 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.301909975669098 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.120889679715306 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.318658536585369 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.045580985915492 ms/frame
12:07:43 INFO - PID 13363 |
12:07:43 INFO - PID 13363 | Cycle 1(12): loaded http://localhost:36728/tests/video/video_playback.html (next: http://localhost:36728/tests/video/video_playback.html)
12:07:44 INFO - PID 13363 | __start_tp_report
12:07:44 INFO - PID 13363 | _x_x_mozilla_page_load
12:07:44 INFO - PID 13363 | _x_x_mozilla_page_load_details
12:07:44 INFO - PID 13363 | |i|pagename|runs|
12:07:44 INFO - PID 13363 | |0;240p.120fps.mp4_scale_1_startup;2.608969565217391;2.61141862489121;2.6526038903625113;2.6180933682373473;2.6135757839721254;2.602107545533391;2.6114360313315927;2.6182417102966844;2.6161595466434178;2.608830434782609;2.6088782608695653;2.6138240418118466
12:07:44 INFO - PID 13363 | |1;240p.120fps.mp4_scale_1_inclip;2.591878238341969;2.5988246753246758;2.587503234152652;2.5885252263906855;2.5919494818652846;2.5875808538163;2.59470168612192;2.587975420439845;2.5979870129870126;2.59812987012987;2.5908743523316065;2.59089378238342
12:07:44 INFO - PID 13363 | |2;240p.120fps.mp4_scale_1.1_startup;2.644008810572688;2.648270079435128;2.6439559471365635;2.6409991197183094;2.6531078691423517;2.6458509700176363;2.63915127528584;2.6414832746478876;2.653015030946065;2.655446902654867;2.6502738515901063;2.643220264317181
12:07:44 INFO - PID 13363 | |3;240p.120fps.mp4_scale_1.1_inclip;2.6324605263157896;2.6358695652173925;2.636238471673254;2.6316447368421056;2.6389709762532996;2.6249212598425204;2.63522397891963;2.638845646437996;2.6259448818897653;2.6358168642951263;2.6320065789473683;2.6257874015748035
12:07:44 INFO - PID 13363 | |4;240p.120fps.mp4_scale_2_startup;3.2328340517241374;3.223340494092374;3.2086631016042766;3.21617899249732;3.2161521972132907;3.2157609860664524;3.20567841880342;3.2159217577706323;3.2095347593582897;3.2125481798715207;3.2231095596133192;3.2122591006423984
12:07:44 INFO - PID 13363 | |5;240p.120fps.mp4_scale_2_inclip;3.200952000000002;3.2111476725521673;3.2105858747993605;3.2063221153846166;3.2113723916532897;3.2104173354735153;3.2062580128205127;3.215844051446944;3.200672;3.206338141025643;3.20551282051282;3.206506410256411
12:07:44 INFO - PID 13363 | |6;480p.60fps.webm_scale_1_startup;4.232129760225672;4.645309597523221;4.688093749999998;4.255879432624112;4.694921752738654;4.6810062402496095;4.3166546762589935;4.342547033285093;4.317863309352517;4.238679378531072;4.545507575757576;4.392825768667642
12:07:44 INFO - PID 13363 | |7;480p.60fps.webm_scale_1_inclip;4.203098739495797;4.652883720930228;4.59810344827586;4.2924678111588;4.685222482435598;4.69680751173709;4.329902597402599;4.301763440860214;4.246592356687899;4.1930712788259985;4.526764705882357;4.367489082969435
12:07:44 INFO - PID 13363 | |8;480p.60fps.webm_scale_1.1_startup;4.380350364963505;4.498763118440778;4.673224299065425;4.225302816901409;4.4447703703703665;4.6887890625000015;4.608387096774195;4.643978328173372;4.400095307917891;4.267446657183504;4.311235632183907;4.478104477611938
12:07:44 INFO - PID 13363 | |9;480p.60fps.webm_scale_1.1_inclip;4.3581372549019575;4.436164079822623;4.475067114093958;4.1756054279749435;4.618937644341801;4.608548387096776;4.7071411764705875;4.415827814569534;4.588830275229356;4.159823284823285;4.5869839449541265;4.663414918414917
12:07:44 INFO - PID 13363 | |10;480p.60fps.webm_scale_2_startup;4.846720516962847;4.926847290640396;4.808525641025642;4.831674718196459;4.847560581583198;4.8474555735056555;4.832495974235104;4.855064724919089;4.83899193548387;4.847592891760905;4.91832786885246;4.848093699515349
12:07:44 INFO - PID 13363 | |11;480p.60fps.webm_scale_2_inclip;4.8445883777239676;4.832898550724637;4.78454545454546;4.807560096153845;4.892078239608804;4.751888361045126;4.752244655581947;4.809591346153848;4.809963942307691;4.809026442307693;4.87991463414634;4.808485576923072
12:07:44 INFO - PID 13363 | |12;1080p.60fps.mp4_scale_1_startup;7.336124694376533;7.317804878048778;7.283082524271839;7.299270072992701;7.371842751842744;7.299683698296842;7.317195121951218;7.335281173594135;7.319439024390247;7.335550122249392;7.334975550122252;7.301119221411188
12:07:44 INFO - PID 13363 | |13;1080p.60fps.mp4_scale_1_inclip;7.0678621908127015;7.067491166077733;7.045440140845066;7.093333333333332;7.069363957597189;7.04241197183098;7.0952127659574415;7.076448763250891;7.017315789473676;7.093226950354613;7.068215547703181;7.067455830388706
12:07:44 INFO - PID 13363 | |14;1080p.60fps.mp4_scale_1.1_startup;7.317975609756104;7.283834951456298;7.323378048780485;7.3186829268292595;7.282390776699032;7.301192214111924;7.301034063260337;7.283810679611651;7.33621026894866;7.248647342995157;7.2828398058252395;7.301909975669098
12:07:44 INFO - PID 13363 | |15;1080p.60fps.mp4_scale_1.1_inclip;7.067879858657241;7.0937765957446794;7.121156583629887;7.095478723404265;7.1433214285714195;7.043063380281701;7.1177402135231285;7.092765957446795;7.069664310954051;7.068180212014154;7.070123674911665;7.120889679715306
12:07:44 INFO - PID 13363 | |16;1080p.60fps.mp4_scale_2_startup;7.389926108374379;7.302554744525544;7.3194512195121915;7.281783980582527;7.300535279805345;7.282245145631077;7.39068965517242;7.299586374695875;7.3010462287104705;7.372260442260448;7.317329268292691;7.318658536585369
12:07:44 INFO - PID 13363 | |17;1080p.60fps.mp4_scale_2_inclip;7.018649122807026;7.045545774647879;7.068215547703181;7.0707067137809005;7.117491103202845;7.093758865248209;7.070318021201419;7.020210526315797;7.095106382978722;7.068851590106009;7.045440140845066;7.045580985915492
12:07:44 INFO - PID 13363 | __end_tp_report
12:07:44 INFO - PID 13363 | __start_cc_report
12:07:44 INFO - PID 13363 | _x_x_mozilla_cycle_collect,606
12:07:44 INFO - PID 13363 | __end_cc_report
12:07:44 INFO - PID 13363 | __startTimestamp1510344464118__endTimestamp
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | ------- Summary: start -------
12:07:44 INFO - PID 13363 | Number of tests: 18
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.62 Median:2.61 stddev:0.01 (0.5%) stddev-sans-first:0.01
12:07:44 INFO - PID 13363 | Values: 2.6 2.6 2.7 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.59 Median:2.59 stddev:0.00 (0.2%) stddev-sans-first:0.00
12:07:44 INFO - PID 13363 | Values: 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.65 Median:2.65 stddev:0.01 (0.2%) stddev-sans-first:0.01
12:07:44 INFO - PID 13363 | Values: 2.6 2.6 2.6 2.6 2.7 2.6 2.6 2.6 2.7 2.7 2.7 2.6
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.63 Median:2.64 stddev:0.00 (0.2%) stddev-sans-first:0.01
12:07:44 INFO - PID 13363 | Values: 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.22 Median:3.22 stddev:0.01 (0.2%) stddev-sans-first:0.01
12:07:44 INFO - PID 13363 | Values: 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.21 Median:3.21 stddev:0.00 (0.1%) stddev-sans-first:0.00
12:07:44 INFO - PID 13363 | Values: 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.45 Median:4.47 stddev:0.19 (4.3%) stddev-sans-first:0.19
12:07:44 INFO - PID 13363 | Values: 4.2 4.6 4.7 4.3 4.7 4.7 4.3 4.3 4.3 4.2 4.5 4.4
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.42 Median:4.45 stddev:0.19 (4.4%) stddev-sans-first:0.19
12:07:44 INFO - PID 13363 | Values: 4.2 4.7 4.6 4.3 4.7 4.7 4.3 4.3 4.2 4.2 4.5 4.4
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.47 Median:4.49 stddev:0.16 (3.5%) stddev-sans-first:0.16
12:07:44 INFO - PID 13363 | Values: 4.4 4.5 4.7 4.2 4.4 4.7 4.6 4.6 4.4 4.3 4.3 4.5
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.48 Median:4.59 stddev:0.18 (3.9%) stddev-sans-first:0.19
12:07:44 INFO - PID 13363 | Values: 4.4 4.4 4.5 4.2 4.6 4.6 4.7 4.4 4.6 4.2 4.6 4.7
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.85 Median:4.85 stddev:0.03 (0.7%) stddev-sans-first:0.04
12:07:44 INFO - PID 13363 | Values: 4.8 4.9 4.8 4.8 4.8 4.8 4.8 4.9 4.8 4.8 4.9 4.8
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.82 Median:4.81 stddev:0.04 (0.9%) stddev-sans-first:0.04
12:07:44 INFO - PID 13363 | Values: 4.8 4.8 4.8 4.8 4.9 4.8 4.8 4.8 4.8 4.8 4.9 4.8
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.32 Median:7.33 stddev:0.02 (0.3%) stddev-sans-first:0.02
12:07:44 INFO - PID 13363 | Values: 7.3 7.3 7.3 7.3 7.4 7.3 7.3 7.3 7.3 7.3 7.3 7.3
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:7.07 Median:7.07 stddev:0.02 (0.3%) stddev-sans-first:0.02
12:07:44 INFO - PID 13363 | Values: 7.1 7.1 7.0 7.1 7.1 7.0 7.1 7.1 7.0 7.1 7.1 7.1
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.30 Median:7.30 stddev:0.02 (0.3%) stddev-sans-first:0.02
12:07:44 INFO - PID 13363 | Values: 7.3 7.3 7.3 7.3 7.3 7.3 7.3 7.3 7.3 7.2 7.3 7.3
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:7.09 Median:7.09 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:07:44 INFO - PID 13363 | Values: 7.1 7.1 7.1 7.1 7.1 7.0 7.1 7.1 7.1 7.1 7.1 7.1
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.32 Median:7.32 stddev:0.04 (0.5%) stddev-sans-first:0.03
12:07:44 INFO - PID 13363 | Values: 7.4 7.3 7.3 7.3 7.3 7.3 7.4 7.3 7.3 7.4 7.3 7.3
12:07:44 INFO - PID 13363 |
12:07:44 INFO - PID 13363 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:7.06 Median:7.07 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:07:44 INFO - PID 13363 | Values: 7.0 7.0 7.1 7.1 7.1 7.1 7.1 7.0 7.1 7.1 7.0 7.0
12:07:44 INFO - PID 13363 | -------- Summary: end --------
12:07:44 INFO - PID 13363 |
12:07:44 INFO - TEST-INFO | 13363: exit 0
12:07:44 INFO - TEST-OK | basic_compositor_video | took 560058ms
12:07:44 INFO - TEST-START | glvideo
12:07:44 INFO - Initialising browser for glvideo test...
12:07:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36728/getInfo.html -profile /tmp/tmpesoAPf/profile
12:07:44 INFO - TEST-INFO | started process 14649 (/builds/slave/test/build/application/firefox/firefox http://localhost:36728/getInfo.html)
12:07:50 INFO - TEST-INFO | 14649: exit 0
12:07:50 INFO - Browser initialized.
12:07:50 INFO - Running cycle 1/1 for glvideo test...
12:07:50 INFO - Using env: {'DISPLAY': ':0',
12:07:50 INFO - 'HOME': '/home/cltbld',
12:07:50 INFO - 'JSGC_DISABLE_POISONING': '1',
12:07:50 INFO - 'LANG': 'en_US.UTF-8',
12:07:50 INFO - 'LANGUAGE': 'en_US:en',
12:07:50 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:07:50 INFO - 'LOGNAME': 'cltbld',
12:07:50 INFO - 'MAIL': '/var/mail/cltbld',
12:07:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:07:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:07:50 INFO - 'MOZ_ACCELERATED': '1',
12:07:50 INFO - 'MOZ_CRASHREPORTER': '1',
12:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:07:50 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:07:50 INFO - 'MOZ_NO_REMOTE': '1',
12:07:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:07:50 INFO - 'MOZ_WEBRENDER': '1',
12:07:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:07:50 INFO - 'NO_EM_RESTART': '1',
12:07:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:07:50 INFO - 'PWD': '/builds/slave/test',
12:07:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:07:50 INFO - 'RUST_BACKTRACE': 'full',
12:07:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:07:50 INFO - 'SHELL': '/bin/bash',
12:07:50 INFO - 'SHLVL': '1',
12:07:50 INFO - 'TERM': 'linux',
12:07:50 INFO - 'TMOUT': '86400',
12:07:50 INFO - 'USER': 'cltbld',
12:07:50 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
12:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:07:50 INFO - '_': '/tools/buildbot/bin/python'}
12:07:50 INFO - TEST-INFO | started process 14929 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpesoAPf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:07:51 INFO - PID 14929 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:07:51 INFO - PID 14929 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:07:51 INFO - PID 14929 |
12:07:51 INFO - PID 14929 | (/builds/slave/test/build/application/firefox/firefox:14991): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:07:51 INFO - PID 14929 |
12:07:52 INFO - PID 14929 | [talos glvideo result] Mean tick time across 100 ticks: 6.8049 ms
12:07:52 INFO - PID 14929 | Cycle 1(1): loaded http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html)
12:07:54 INFO - PID 14929 | [talos glvideo result] Mean tick time across 100 ticks: 6.4860500000000005 ms
12:07:54 INFO - PID 14929 | Cycle 1(2): loaded http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html)
12:07:55 INFO - PID 14929 | [talos glvideo result] Mean tick time across 100 ticks: 6.62135 ms
12:07:55 INFO - PID 14929 | Cycle 1(3): loaded http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html)
12:07:56 INFO - PID 14929 | [talos glvideo result] Mean tick time across 100 ticks: 6.57945 ms
12:07:56 INFO - PID 14929 | Cycle 1(4): loaded http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html)
12:07:58 INFO - PID 14929 | [talos glvideo result] Mean tick time across 100 ticks: 6.68005 ms
12:07:58 INFO - PID 14929 | Cycle 1(5): loaded http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36728/tests/webgl/benchmarks/video/video_upload.html)
12:07:58 INFO - PID 14929 | __start_tp_report
12:07:58 INFO - PID 14929 | _x_x_mozilla_page_load
12:07:58 INFO - PID 14929 | _x_x_mozilla_page_load_details
12:07:58 INFO - PID 14929 | |i|pagename|runs|
12:07:58 INFO - PID 14929 | |0;Mean tick time across 100 ticks: ;6.8049;6.4860500000000005;6.62135;6.57945;6.68005
12:07:58 INFO - PID 14929 | __end_tp_report
12:07:58 INFO - PID 14929 | __start_cc_report
12:07:58 INFO - PID 14929 | _x_x_mozilla_cycle_collect,283
12:07:58 INFO - PID 14929 | __end_cc_report
12:07:58 INFO - PID 14929 | __startTimestamp1510344478554__endTimestamp
12:07:58 INFO - PID 14929 |
12:07:58 INFO - PID 14929 | ------- Summary: start -------
12:07:58 INFO - PID 14929 | Number of tests: 1
12:07:58 INFO - PID 14929 |
12:07:58 INFO - PID 14929 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.63 Median:6.62 stddev:0.12 (1.8%) stddev-sans-first:0.08
12:07:58 INFO - PID 14929 | Values: 6.8 6.5 6.6 6.6 6.7
12:07:58 INFO - PID 14929 | -------- Summary: end --------
12:07:58 INFO - PID 14929 |
12:07:58 INFO - PID 14929 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:07:59 INFO - TEST-INFO | 14929: exit 0
12:07:59 INFO - TEST-OK | glvideo | took 14373ms
12:07:59 INFO - TEST-START | displaylist_mutate
12:07:59 INFO - Initialising browser for displaylist_mutate test...
12:07:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36728/getInfo.html -profile /tmp/tmpcagFdq/profile
12:07:59 INFO - TEST-INFO | started process 15164 (/builds/slave/test/build/application/firefox/firefox http://localhost:36728/getInfo.html)
12:08:05 INFO - TEST-INFO | 15164: exit 0
12:08:05 INFO - Browser initialized.
12:08:05 INFO - Running cycle 1/1 for displaylist_mutate test...
12:08:05 INFO - Using env: {'DISPLAY': ':0',
12:08:05 INFO - 'HOME': '/home/cltbld',
12:08:05 INFO - 'JSGC_DISABLE_POISONING': '1',
12:08:05 INFO - 'LANG': 'en_US.UTF-8',
12:08:05 INFO - 'LANGUAGE': 'en_US:en',
12:08:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:08:05 INFO - 'LOGNAME': 'cltbld',
12:08:05 INFO - 'MAIL': '/var/mail/cltbld',
12:08:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:08:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:08:05 INFO - 'MOZ_ACCELERATED': '1',
12:08:05 INFO - 'MOZ_CRASHREPORTER': '1',
12:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:08:05 INFO - 'MOZ_NO_REMOTE': '1',
12:08:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:08:05 INFO - 'MOZ_WEBRENDER': '1',
12:08:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:05 INFO - 'NO_EM_RESTART': '1',
12:08:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:05 INFO - 'PWD': '/builds/slave/test',
12:08:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:08:05 INFO - 'RUST_BACKTRACE': 'full',
12:08:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:08:05 INFO - 'SHELL': '/bin/bash',
12:08:05 INFO - 'SHLVL': '1',
12:08:05 INFO - 'TERM': 'linux',
12:08:05 INFO - 'TMOUT': '86400',
12:08:05 INFO - 'USER': 'cltbld',
12:08:05 INFO - 'XDG_SESSION_COOKIE': 'b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139',
12:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:05 INFO - '_': '/tools/buildbot/bin/python'}
12:08:05 INFO - TEST-INFO | started process 15445 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcagFdq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:08:06 INFO - PID 15445 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:08:06 INFO - PID 15445 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:08:06 INFO - PID 15445 |
12:08:06 INFO - PID 15445 | (/builds/slave/test/build/application/firefox/firefox:15507): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:08:06 INFO - PID 15445 |
12:08:24 INFO - PID 15445 | Cycle 1(1): loaded http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html)
12:08:42 INFO - PID 15445 | Cycle 1(2): loaded http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html)
12:09:00 INFO - PID 15445 | Cycle 1(3): loaded http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html)
12:09:19 INFO - PID 15445 | Cycle 1(4): loaded http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html)
12:09:37 INFO - PID 15445 | Cycle 1(5): loaded http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36728/tests/layout/benchmarks/displaylist_mutate.html)
12:09:37 INFO - PID 15445 | Waiting for idle-callback
12:09:38 INFO - PID 15445 | __start_tp_report
12:09:38 INFO - PID 15445 | _x_x_mozilla_page_load
12:09:38 INFO - PID 15445 | _x_x_mozilla_page_load_details
12:09:38 INFO - PID 15445 | |i|pagename|runs|
12:09:38 INFO - PID 15445 | |0;/displaylist_mutate.html;17425.545;17493.565;17417.61;17695.75;17518.850000000002
12:09:38 INFO - PID 15445 | __end_tp_report
12:09:38 INFO - PID 15445 | __start_cc_report
12:09:38 INFO - PID 15445 | _x_x_mozilla_cycle_collect,221
12:09:38 INFO - PID 15445 | __end_cc_report
12:09:38 INFO - PID 15445 | __startTimestamp1510344578006__endTimestamp
12:09:38 INFO - PID 15445 |
12:09:38 INFO - PID 15445 | ------- Summary: start -------
12:09:38 INFO - PID 15445 | Number of tests: 1
12:09:38 INFO - PID 15445 |
12:09:38 INFO - PID 15445 | [#0] /displaylist_mutate.html Cycles:5 Average:17510.26 Median:17493.56 stddev:112.38 (0.6%) stddev-sans-first:117.68
12:09:38 INFO - PID 15445 | Values: 17425.5 17493.6 17417.6 17695.8 17518.9
12:09:38 INFO - PID 15445 | -------- Summary: end --------
12:09:38 INFO - PID 15445 |
12:09:38 INFO - TEST-INFO | 15445: exit 0
12:09:38 INFO - TEST-OK | displaylist_mutate | took 99449ms
12:09:38 INFO - SUITE-END | took 673s
12:09:39 INFO - Completed test suite (00:11:14)
12:09:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.582440484180155, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.608969565217391, 2.61141862489121, 2.6526038903625113, 2.6180933682373473, 2.6135757839721254, 2.602107545533391, 2.6114360313315927, 2.6182417102966844, 2.6161595466434178, 2.608830434782609, 2.6088782608695653, 2.6138240418118466], "value": 2.6135757839721254, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.591878238341969, 2.5988246753246758, 2.587503234152652, 2.5885252263906855, 2.5919494818652846, 2.5875808538163, 2.59470168612192, 2.587975420439845, 2.5979870129870126, 2.59812987012987, 2.5908743523316065, 2.59089378238342], "value": 2.59089378238342, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.644008810572688, 2.648270079435128, 2.6439559471365635, 2.6409991197183094, 2.6531078691423517, 2.6458509700176363, 2.63915127528584, 2.6414832746478876, 2.653015030946065, 2.655446902654867, 2.6502738515901063, 2.643220264317181], "value": 2.6458509700176363, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.6324605263157896, 2.6358695652173925, 2.636238471673254, 2.6316447368421056, 2.6389709762532996, 2.6249212598425204, 2.63522397891963, 2.638845646437996, 2.6259448818897653, 2.6358168642951263, 2.6320065789473683, 2.6257874015748035], "value": 2.63522397891963, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.2328340517241374, 3.223340494092374, 3.2086631016042766, 3.21617899249732, 3.2161521972132907, 3.2157609860664524, 3.20567841880342, 3.2159217577706323, 3.2095347593582897, 3.2125481798715207, 3.2231095596133192, 3.2122591006423984], "value": 3.2157609860664524, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.200952000000002, 3.2111476725521673, 3.2105858747993605, 3.2063221153846166, 3.2113723916532897, 3.2104173354735153, 3.2062580128205127, 3.215844051446944, 3.200672, 3.206338141025643, 3.20551282051282, 3.206506410256411], "value": 3.206506410256411, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.232129760225672, 4.645309597523221, 4.688093749999998, 4.255879432624112, 4.694921752738654, 4.6810062402496095, 4.3166546762589935, 4.342547033285093, 4.317863309352517, 4.238679378531072, 4.545507575757576, 4.392825768667642], "value": 4.392825768667642, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.203098739495797, 4.652883720930228, 4.59810344827586, 4.2924678111588, 4.685222482435598, 4.69680751173709, 4.329902597402599, 4.301763440860214, 4.246592356687899, 4.1930712788259985, 4.526764705882357, 4.367489082969435], "value": 4.367489082969435, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.380350364963505, 4.498763118440778, 4.673224299065425, 4.225302816901409, 4.4447703703703665, 4.6887890625000015, 4.608387096774195, 4.643978328173372, 4.400095307917891, 4.267446657183504, 4.311235632183907, 4.478104477611938], "value": 4.478104477611938, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.3581372549019575, 4.436164079822623, 4.475067114093958, 4.1756054279749435, 4.618937644341801, 4.608548387096776, 4.7071411764705875, 4.415827814569534, 4.588830275229356, 4.159823284823285, 4.5869839449541265, 4.663414918414917], "value": 4.5869839449541265, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.846720516962847, 4.926847290640396, 4.808525641025642, 4.831674718196459, 4.847560581583198, 4.8474555735056555, 4.832495974235104, 4.855064724919089, 4.83899193548387, 4.847592891760905, 4.91832786885246, 4.848093699515349], "value": 4.847560581583198, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.8445883777239676, 4.832898550724637, 4.78454545454546, 4.807560096153845, 4.892078239608804, 4.751888361045126, 4.752244655581947, 4.809591346153848, 4.809963942307691, 4.809026442307693, 4.87991463414634, 4.808485576923072], "value": 4.809026442307693, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.336124694376533, 7.317804878048778, 7.283082524271839, 7.299270072992701, 7.371842751842744, 7.299683698296842, 7.317195121951218, 7.335281173594135, 7.319439024390247, 7.335550122249392, 7.334975550122252, 7.301119221411188], "value": 7.317804878048778, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.0678621908127015, 7.067491166077733, 7.045440140845066, 7.093333333333332, 7.069363957597189, 7.04241197183098, 7.0952127659574415, 7.076448763250891, 7.017315789473676, 7.093226950354613, 7.068215547703181, 7.067455830388706], "value": 7.068215547703181, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.317975609756104, 7.283834951456298, 7.323378048780485, 7.3186829268292595, 7.282390776699032, 7.301192214111924, 7.301034063260337, 7.283810679611651, 7.33621026894866, 7.248647342995157, 7.2828398058252395, 7.301909975669098], "value": 7.301034063260337, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.067879858657241, 7.0937765957446794, 7.121156583629887, 7.095478723404265, 7.1433214285714195, 7.043063380281701, 7.1177402135231285, 7.092765957446795, 7.069664310954051, 7.068180212014154, 7.070123674911665, 7.120889679715306], "value": 7.0937765957446794, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.389926108374379, 7.302554744525544, 7.3194512195121915, 7.281783980582527, 7.300535279805345, 7.282245145631077, 7.39068965517242, 7.299586374695875, 7.3010462287104705, 7.372260442260448, 7.317329268292691, 7.318658536585369], "value": 7.302554744525544, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.018649122807026, 7.045545774647879, 7.068215547703181, 7.0707067137809005, 7.117491103202845, 7.093758865248209, 7.070318021201419, 7.020210526315797, 7.095106382978722, 7.068851590106009, 7.045440140845066, 7.045580985915492], "value": 7.068851590106009, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.8049, 6.4860500000000005, 6.62135, 6.57945, 6.68005], "value": 6.6004, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [17425.545, 17493.565, 17417.61, 17695.75, 17518.850000000002], "value": 17506.2075, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
12:09:39 INFO - Return code: 0
12:09:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
12:09:39 INFO - # TBPL SUCCESS #
12:09:39 INFO - Running post-action listener: _package_coverage_data
12:09:39 INFO - Running post-action listener: _resource_record_post_action
12:09:39 INFO - [mozharness: 2017-11-10 20:09:39.158819Z] Finished run-tests step (success)
12:09:39 INFO - Running post-run listener: _resource_record_post_run
12:09:39 INFO - Total resource usage - Wall time: 694s; CPU: 43.0%; Read bytes: 2867200; Write bytes: 347885568; Read time: 560; Write time: 190880
12:09:39 INFO - TinderboxPrint: CPU usage
42.9%
12:09:39 INFO - TinderboxPrint: I/O read bytes / time
2,867,200 / 560
12:09:39 INFO - TinderboxPrint: I/O write bytes / time
347,885,568 / 190,880
12:09:39 INFO - TinderboxPrint: CPU idle
2,988.7 (57.0%)
12:09:39 INFO - TinderboxPrint: CPU system
668.4 (12.7%)
12:09:39 INFO - TinderboxPrint: CPU user
1,569.9 (29.9%)
12:09:39 INFO - TinderboxPrint: Swap in / out
0 / 0
12:09:39 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 6434816; Read time: 0; Write time: 848
12:09:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
12:09:39 INFO - run-tests - Wall time: 675s; CPU: 44.0%; Read bytes: 2867200; Write bytes: 341450752; Read time: 560; Write time: 190032
12:09:39 INFO - Running post-run listener: _upload_blobber_files
12:09:39 INFO - Blob upload gear active.
12:09:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
12:09:39 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
12:09:39 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']
12:09:39 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
12:09:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: 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
12:09:39 INFO - SNIMissingWarning
12:09:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:39 INFO - InsecurePlatformWarning
12:09:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: 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.)
12:09:39 INFO - SubjectAltNameWarning
12:09:39 INFO - (blobuploader) - INFO - Open directory for files ...
12:09:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
12:09:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:09:39 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:09:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:40 INFO - InsecurePlatformWarning
12:09:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: 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.)
12:09:40 INFO - SubjectAltNameWarning
12:09:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:40 INFO - InsecurePlatformWarning
12:09:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
12:09:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:09:40 INFO - (blobuploader) - INFO - Done attempting.
12:09:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
12:09:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:09:40 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:09:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:40 INFO - InsecurePlatformWarning
12:09:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: 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.)
12:09:41 INFO - SubjectAltNameWarning
12:09:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:45 INFO - InsecurePlatformWarning
12:09:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:09:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:09:45 INFO - (blobuploader) - INFO - Done attempting.
12:09:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
12:09:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:09:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:09:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:46 INFO - InsecurePlatformWarning
12:09:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: 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.)
12:09:46 INFO - SubjectAltNameWarning
12:09:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:46 INFO - InsecurePlatformWarning
12:09:46 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
12:09:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:09:46 INFO - (blobuploader) - INFO - Done attempting.
12:09:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
12:09:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:09:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:09:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:46 INFO - InsecurePlatformWarning
12:09:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: 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.)
12:09:47 INFO - SubjectAltNameWarning
12:09:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: 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
12:09:47 INFO - InsecurePlatformWarning
12:09:47 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
12:09:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:09:47 INFO - (blobuploader) - INFO - Done attempting.
12:09:47 INFO - (blobuploader) - INFO - Iteration through files over.
12:09:47 INFO - Return code: 0
12:09:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
12:09:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
12:09:47 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/618bffe222cc18db5a242f2eeb88fd78449a5bd97f2e2b1596b049443014534399931427210772515de18cb8da1c43673e60d1fe7d21e19a99d792d7cdab848f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a749d9352aed1efadd89ad92e3d8dcc8200bc0c9301557fb58131c2fdb3031b598b6cd51befcd5e527ec0f6c5c9416442396cb4aff3c7f66f61e419928da57d", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a1b492dad43ad84c8b4d65800f164160c0363dbe82626e3f1f9b394dec3547b58a105152a2127c0b2196dc662ee25300e89dd1cd746164c13092493d21b9a3", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/717e984b55456186ab20d87bc77a1f937b349cbfeeff89ae7fe1eacd7ef1b58132f26d41b13d6e60cab94504fe277359508e8a3b994730729a368cc95b392e36"}
12:09:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
12:09:47 INFO - Writing to file /builds/slave/test/properties/blobber_files
12:09:47 INFO - Contents:
12:09:47 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/618bffe222cc18db5a242f2eeb88fd78449a5bd97f2e2b1596b049443014534399931427210772515de18cb8da1c43673e60d1fe7d21e19a99d792d7cdab848f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a749d9352aed1efadd89ad92e3d8dcc8200bc0c9301557fb58131c2fdb3031b598b6cd51befcd5e527ec0f6c5c9416442396cb4aff3c7f66f61e419928da57d", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a1b492dad43ad84c8b4d65800f164160c0363dbe82626e3f1f9b394dec3547b58a105152a2127c0b2196dc662ee25300e89dd1cd746164c13092493d21b9a3", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/717e984b55456186ab20d87bc77a1f937b349cbfeeff89ae7fe1eacd7ef1b58132f26d41b13d6e60cab94504fe277359508e8a3b994730729a368cc95b392e36"}
12:09:47 INFO - Running post-run listener: copy_logs_to_upload_dir
12:09:47 INFO - Copying logs to upload dir...
12:09:47 INFO - mkdir: /builds/slave/test/build/upload/logs
12:09:47 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=756.815289
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 36 secs) (at 2017-11-10 12:09:47.846172) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-10 12:09:47.849945) =========
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=b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/618bffe222cc18db5a242f2eeb88fd78449a5bd97f2e2b1596b049443014534399931427210772515de18cb8da1c43673e60d1fe7d21e19a99d792d7cdab848f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a749d9352aed1efadd89ad92e3d8dcc8200bc0c9301557fb58131c2fdb3031b598b6cd51befcd5e527ec0f6c5c9416442396cb4aff3c7f66f61e419928da57d", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a1b492dad43ad84c8b4d65800f164160c0363dbe82626e3f1f9b394dec3547b58a105152a2127c0b2196dc662ee25300e89dd1cd746164c13092493d21b9a3", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/717e984b55456186ab20d87bc77a1f937b349cbfeeff89ae7fe1eacd7ef1b58132f26d41b13d6e60cab94504fe277359508e8a3b994730729a368cc95b392e36"}
build_url:https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011214
build_url: 'https://queue.taskcluster.net/v1/task/EXw57MOCS5iSEAib7P4deg/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/618bffe222cc18db5a242f2eeb88fd78449a5bd97f2e2b1596b049443014534399931427210772515de18cb8da1c43673e60d1fe7d21e19a99d792d7cdab848f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a749d9352aed1efadd89ad92e3d8dcc8200bc0c9301557fb58131c2fdb3031b598b6cd51befcd5e527ec0f6c5c9416442396cb4aff3c7f66f61e419928da57d", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10a1b492dad43ad84c8b4d65800f164160c0363dbe82626e3f1f9b394dec3547b58a105152a2127c0b2196dc662ee25300e89dd1cd746164c13092493d21b9a3", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/717e984b55456186ab20d87bc77a1f937b349cbfeeff89ae7fe1eacd7ef1b58132f26d41b13d6e60cab94504fe277359508e8a3b994730729a368cc95b392e36"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-10 12:09:47.880746) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 12:09:47.881131) =========
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=b299c01c490a6b547975689e000001ae-1510343676.10796-1958421139
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004504
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 12:09:47.964851) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-10 12:09:47.965268) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-10 12:09:47.965701) =========
========= Total master_lag: 0.21 =========