builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-002
starttime: 1511607885.49
results: success (0)
revision: 890ae45c3d15d5d86a62dc418fac22a4e49f8751
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:45.493770) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:45.494188) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:45.494476) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:45.584726) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:45.585003) =========
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=a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-25 03:04:45-- 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% 101K=0.1s
2017-11-25 03:04:45 (101 KB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.376594
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:45.999712) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:46.000005) =========
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=a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.071567
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:46.088557) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-25 03:04:46.088907) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 890ae45c3d15d5d86a62dc418fac22a4e49f8751 --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 890ae45c3d15d5d86a62dc418fac22a4e49f8751 --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=a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-25 03:04:46,143 truncating revision to first 12 chars
2017-11-25 03:04:46,143 Setting DEBUG logging.
2017-11-25 03:04:46,143 attempt 1/10
2017-11-25 03:04:46,143 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/890ae45c3d15?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-25 03:04:47,256 unpacking tar archive at: mozilla-central-890ae45c3d15/testing/mozharness/
program finished with exit code 0
elapsedTime=1.694668
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-25 03:04:47.803450) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:47.803963) =========
script_repo_revision: 890ae45c3d15d5d86a62dc418fac22a4e49f8751
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:47.804601) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:47.805057) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-25 03:04:47.820995) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 17 secs) (at 2017-11-25 03:04:47.821381) =========
/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=a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:04:48 INFO - MultiFileLogger online at 20171125 03:04:48 in /builds/slave/test
03:04:48 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
03:04:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:04:48 INFO - {'append_to_log': False,
03:04:48 INFO - 'base_work_dir': '/builds/slave/test',
03:04:48 INFO - 'blob_upload_branch': 'Firefox',
03:04:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:04:48 INFO - 'branch': 'Firefox',
03:04:48 INFO - 'buildbot_json_path': 'buildprops.json',
03:04:48 INFO - 'code_coverage': False,
03:04:48 INFO - 'config_files': ('talos/linux_config.py',),
03:04:48 INFO - 'default_actions': ('clobber',
03:04:48 INFO - 'read-buildbot-config',
03:04:48 INFO - 'download-and-extract',
03:04:48 INFO - 'populate-webroot',
03:04:48 INFO - 'create-virtualenv',
03:04:48 INFO - 'install',
03:04:48 INFO - 'setup-mitmproxy',
03:04:48 INFO - 'run-tests'),
03:04:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:04:48 INFO - 'disable_ccov_upload': False,
03:04:48 INFO - 'disable_stylo': False,
03:04:48 INFO - 'download_minidump_stackwalk': True,
03:04:48 INFO - 'download_symbols': 'ondemand',
03:04:48 INFO - 'e10s': False,
03:04:48 INFO - 'enable_stylo': False,
03:04:48 INFO - 'enable_webrender': False,
03:04:48 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:04:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:04:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:04:48 INFO - 'gecko_profile': False,
03:04:48 INFO - 'gecko_profile_interval': 0,
03:04:48 INFO - 'installer_path': 'installer.exe',
03:04:48 INFO - 'jsd_code_coverage': False,
03:04:48 INFO - 'log_level': 'info',
03:04:48 INFO - 'log_name': 'talos',
03:04:48 INFO - 'log_to_console': True,
03:04:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:04:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:04:48 INFO - 'opt_config_files': (),
03:04:48 INFO - 'pip_index': False,
03:04:48 INFO - 'suite': 'g4-e10s',
03:04:48 INFO - 'system_bits': '32',
03:04:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:04:48 INFO - 'title': 'talos-linux64-ix-002',
03:04:48 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:04:48 INFO - 'use_talos_json': True,
03:04:48 INFO - 'verify': 'False',
03:04:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:04:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:04:48 INFO - 'work_dir': 'build'}
03:04:48 INFO - [mozharness: 2017-11-25 11:04:48.092932Z] Running clobber step.
03:04:48 INFO - Running pre-action listener: _resource_record_pre_action
03:04:48 INFO - Running main action method: clobber
03:04:48 INFO - rmtree: /builds/slave/test/build
03:04:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:04:50 INFO - Running post-action listener: _resource_record_post_action
03:04:50 INFO - [mozharness: 2017-11-25 11:04:50.146373Z] Finished clobber step (success)
03:04:50 INFO - [mozharness: 2017-11-25 11:04:50.146482Z] Running read-buildbot-config step.
03:04:50 INFO - Running pre-action listener: _resource_record_pre_action
03:04:50 INFO - Running main action method: read_buildbot_config
03:04:50 INFO - Using buildbot properties:
03:04:50 INFO - {
03:04:50 INFO - "project": "",
03:04:50 INFO - "product": "firefox",
03:04:50 INFO - "who": "nerli@mozilla.com",
03:04:50 INFO - "installer_path": "public/build/target.tar.bz2",
03:04:50 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
03:04:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:04:50 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
03:04:50 INFO - "stage_platform": "linux64-qr",
03:04:50 INFO - "basedir": "/builds/slave/test",
03:04:50 INFO - "buildnumber": 218,
03:04:50 INFO - "platform": "ubuntu64_hw_qr",
03:04:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
03:04:50 INFO - "slavebuilddir": "test",
03:04:50 INFO - "taskId": "U9U1xJzASpO94Vy8puY5mQ",
03:04:50 INFO - "branch": "mozilla-central",
03:04:50 INFO - "script_repo_revision": "production",
03:04:50 INFO - "revision": "890ae45c3d15d5d86a62dc418fac22a4e49f8751",
03:04:50 INFO - "slavename": "talos-linux64-ix-002",
03:04:50 INFO - "repo_path": "mozilla-central"
03:04:50 INFO - }
03:04:50 INFO - Finding installer, test and symbols from parent task.
03:04:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U9U1xJzASpO94Vy8puY5mQ'}, attempt #1
03:04:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U9U1xJzASpO94Vy8puY5mQ'}, attempt #1
03:04:51 INFO - Task dependencies: WqwZibDQSLKtqYt5e9GqOQ
03:04:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ'}, attempt #1
03:04:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2
03:04:51 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.test_packages.json
03:04:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.crashreporter-symbols.zip
03:04:51 INFO - Running post-action listener: _resource_record_post_action
03:04:51 INFO - [mozharness: 2017-11-25 11:04:51.499898Z] Finished read-buildbot-config step (success)
03:04:51 INFO - [mozharness: 2017-11-25 11:04:51.500114Z] Running download-and-extract step.
03:04:51 INFO - Running pre-action listener: _resource_record_pre_action
03:04:51 INFO - Running main action method: download_and_extract
03:04:51 INFO - mkdir: /builds/slave/test/build/tests
03:04:51 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')]}
03:04:51 INFO - trying https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.test_packages.json
03:04:51 INFO - Downloading https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:04:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:04:52 INFO - Downloaded 989 bytes.
03:04:52 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:04:52 INFO - Using the following test package requirements:
03:04:52 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:04:52 INFO - u'common': [u'target.common.tests.zip'],
03:04:52 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:04:52 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:04:52 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:04:52 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:04:52 INFO - u'mozbase': [u'target.common.tests.zip'],
03:04:52 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:04:52 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:04:52 INFO - u'web-platform': [u'target.common.tests.zip',
03:04:52 INFO - u'target.web-platform.tests.tar.gz'],
03:04:52 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:04:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:04:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.common.tests.zip
03:04:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:04:52 INFO - Fetch https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.common.tests.zip into memory
03:04:53 INFO - Content-Length response header: 59108971
03:04:53 INFO - Bytes received: 59108971
03:05:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.talos.tests.zip
03:05:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:05:04 INFO - Fetch https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.talos.tests.zip into memory
03:05:05 INFO - Content-Length response header: 18744811
03:05:05 INFO - Bytes received: 18744811
03:05:06 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')]}
03:05:06 INFO - trying https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2
03:05:06 INFO - Downloading https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:05:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:05:07 INFO - Downloaded 62564982 bytes.
03:05:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2
03:05:07 INFO - mkdir: /builds/slave/test/properties
03:05:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:05:07 INFO - Writing to file /builds/slave/test/properties/build_url
03:05:07 INFO - Contents:
03:05:07 INFO - build_url:https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2
03:05:07 INFO - Running post-action listener: _resource_record_post_action
03:05:07 INFO - Running post-action listener: find_tests_for_verification
03:05:07 INFO - Running post-action listener: set_extra_try_arguments
03:05:07 INFO - [mozharness: 2017-11-25 11:05:07.464328Z] Finished download-and-extract step (success)
03:05:07 INFO - [mozharness: 2017-11-25 11:05:07.464423Z] Running populate-webroot step.
03:05:07 INFO - Running pre-action listener: _resource_record_pre_action
03:05:07 INFO - Running main action method: populate_webroot
03:05:07 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:05:07 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['tresize',
03:05:07 INFO - 'tcanvasmark']},
03:05:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:05:07 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['dromaeo_css',
03:05:07 INFO - 'kraken']},
03:05:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:05:07 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:05:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'tests': ['damp', 'tps']},
03:05:07 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['damp', 'tps']},
03:05:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:05:07 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['dromaeo_dom']},
03:05:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
03:05:07 INFO - 'glvideo',
03:05:07 INFO - 'displaylist_mutate']},
03:05:07 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['basic_compositor_video',
03:05:07 INFO - 'glvideo']},
03:05:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:05:07 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['ts_paint_webext',
03:05:07 INFO - 'tp5o_webext']},
03:05:07 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:05:07 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:05:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:05:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:05:07 INFO - 'talos_options': ['--mitmproxy',
03:05:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:05:07 INFO - '--firstNonBlankPaint'],
03:05:07 INFO - 'tests': ['tp6_google_heavy',
03:05:07 INFO - 'tp6_youtube_heavy',
03:05:07 INFO - 'tp6_amazon_heavy',
03:05:07 INFO - 'tp6_facebook_heavy']},
03:05:07 INFO - 'other-e10s': {'tests': ['a11yr',
03:05:07 INFO - 'ts_paint',
03:05:07 INFO - 'tpaint',
03:05:07 INFO - 'sessionrestore',
03:05:07 INFO - 'sessionrestore_many_windows',
03:05:07 INFO - 'sessionrestore_no_auto_restore',
03:05:07 INFO - 'tabpaint',
03:05:07 INFO - 'cpstartup']},
03:05:07 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['a11yr',
03:05:07 INFO - 'ts_paint',
03:05:07 INFO - 'tpaint',
03:05:07 INFO - 'sessionrestore',
03:05:07 INFO - 'sessionrestore_many_windows',
03:05:07 INFO - 'sessionrestore_no_auto_restore',
03:05:07 INFO - 'tabpaint',
03:05:07 INFO - 'cpstartup']},
03:05:07 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:05:07 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:05:07 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['perf_reftest_singletons']},
03:05:07 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['perf_reftest']},
03:05:07 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:05:07 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['speedometer']},
03:05:07 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:05:07 INFO - 'tsvgr_opacity',
03:05:07 INFO - 'tart',
03:05:07 INFO - 'tscrollx',
03:05:07 INFO - 'tsvg_static']},
03:05:07 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['tsvgx',
03:05:07 INFO - 'tsvgr_opacity',
03:05:07 INFO - 'tart',
03:05:07 INFO - 'tscrollx',
03:05:07 INFO - 'tsvg_static']},
03:05:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:05:07 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'talos_options': ['--disable-stylo'],
03:05:07 INFO - 'tests': ['tp5o']},
03:05:07 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:05:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:05:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:05:07 INFO - 'talos_options': ['--mitmproxy',
03:05:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:05:07 INFO - '--firstNonBlankPaint'],
03:05:07 INFO - 'tests': ['tp6_google',
03:05:07 INFO - 'tp6_youtube',
03:05:07 INFO - 'tp6_amazon',
03:05:07 INFO - 'tp6_facebook']},
03:05:07 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:05:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:05:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:05:07 INFO - 'talos_options': ['--disable-stylo',
03:05:07 INFO - '--mitmproxy',
03:05:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:05:07 INFO - '--firstNonBlankPaint'],
03:05:07 INFO - 'tests': ['tp6_google',
03:05:07 INFO - 'tp6_youtube',
03:05:07 INFO - 'tp6_amazon',
03:05:07 INFO - 'tp6_facebook']},
03:05:07 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:05:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:05:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:05:07 INFO - 'talos_options': ['--stylo-threads=1',
03:05:07 INFO - '--mitmproxy',
03:05:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:05:07 INFO - '--firstNonBlankPaint'],
03:05:07 INFO - 'tests': ['tp6_google',
03:05:07 INFO - 'tp6_youtube',
03:05:07 INFO - 'tp6_amazon',
03:05:07 INFO - 'tp6_facebook']},
03:05:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'talos_options': ['--xperf_path',
03:05:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:05:07 INFO - 'tests': ['tp5n']},
03:05:07 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:05:07 INFO - 'talos_options': ['--disable-stylo',
03:05:07 INFO - '--xperf_path',
03:05:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:05:07 INFO - 'tests': ['tp5n']}}}
03:05:07 INFO - Running post-action listener: _resource_record_post_action
03:05:07 INFO - [mozharness: 2017-11-25 11:05:07.482892Z] Finished populate-webroot step (success)
03:05:07 INFO - [mozharness: 2017-11-25 11:05:07.482985Z] Running create-virtualenv step.
03:05:07 INFO - Running pre-action listener: _resource_record_pre_action
03:05:07 INFO - Running main action method: create_virtualenv
03:05:07 INFO - Creating virtualenv /builds/slave/test/build/venv
03:05:07 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
03:05:07 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:05:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:05:09 INFO - Using real prefix '/usr'
03:05:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:05:09 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:05:11 INFO - Installing setuptools, pip, wheel...done.
03:05:11 INFO - Return code: 0
03:05:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:05:11 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')]}
03:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:11 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')]}
03:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:11 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:11 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
03:05:11 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
03:05:11 INFO - Using env: {'DISPLAY': ':0',
03:05:11 INFO - 'HOME': '/home/cltbld',
03:05:11 INFO - 'LANG': 'en_US.UTF-8',
03:05:11 INFO - 'LANGUAGE': 'en_US:en',
03:05:11 INFO - 'LOGNAME': 'cltbld',
03:05:11 INFO - 'MAIL': '/var/mail/cltbld',
03:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:11 INFO - 'MOZ_NO_REMOTE': '1',
03:05:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:11 INFO - 'NO_EM_RESTART': '1',
03:05:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:11 INFO - 'PWD': '/builds/slave/test',
03:05:11 INFO - 'SHELL': '/bin/bash',
03:05:11 INFO - 'SHLVL': '1',
03:05:11 INFO - 'TERM': 'linux',
03:05:11 INFO - 'TMOUT': '86400',
03:05:11 INFO - 'USER': 'cltbld',
03:05:11 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:11 INFO - '_': '/tools/buildbot/bin/python'}
03:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:05:12 INFO - Return code: 0
03:05:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:05:12 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')]}
03:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:12 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')]}
03:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:12 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:12 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
03:05:12 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
03:05:12 INFO - Using env: {'DISPLAY': ':0',
03:05:12 INFO - 'HOME': '/home/cltbld',
03:05:12 INFO - 'LANG': 'en_US.UTF-8',
03:05:12 INFO - 'LANGUAGE': 'en_US:en',
03:05:12 INFO - 'LOGNAME': 'cltbld',
03:05:12 INFO - 'MAIL': '/var/mail/cltbld',
03:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:12 INFO - 'MOZ_NO_REMOTE': '1',
03:05:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:12 INFO - 'NO_EM_RESTART': '1',
03:05:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:12 INFO - 'PWD': '/builds/slave/test',
03:05:12 INFO - 'SHELL': '/bin/bash',
03:05:12 INFO - 'SHLVL': '1',
03:05:12 INFO - 'TERM': 'linux',
03:05:12 INFO - 'TMOUT': '86400',
03:05:12 INFO - 'USER': 'cltbld',
03:05:12 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:12 INFO - '_': '/tools/buildbot/bin/python'}
03:05:12 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:12 INFO - Collecting psutil>=3.1.1
03:05:13 INFO - Installing collected packages: psutil
03:05:13 INFO - Successfully installed psutil-3.1.1
03:05:13 INFO - Return code: 0
03:05:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:13 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:13 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
03:05:13 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
03:05:13 INFO - Using env: {'DISPLAY': ':0',
03:05:13 INFO - 'HOME': '/home/cltbld',
03:05:13 INFO - 'LANG': 'en_US.UTF-8',
03:05:13 INFO - 'LANGUAGE': 'en_US:en',
03:05:13 INFO - 'LOGNAME': 'cltbld',
03:05:13 INFO - 'MAIL': '/var/mail/cltbld',
03:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:13 INFO - 'MOZ_NO_REMOTE': '1',
03:05:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:13 INFO - 'NO_EM_RESTART': '1',
03:05:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:13 INFO - 'PWD': '/builds/slave/test',
03:05:13 INFO - 'SHELL': '/bin/bash',
03:05:13 INFO - 'SHLVL': '1',
03:05:13 INFO - 'TERM': 'linux',
03:05:13 INFO - 'TMOUT': '86400',
03:05:13 INFO - 'USER': 'cltbld',
03:05:13 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:13 INFO - '_': '/tools/buildbot/bin/python'}
03:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:14 INFO - Collecting mozsystemmonitor==0.3
03:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:05:15 INFO - Installing collected packages: mozsystemmonitor
03:05:15 INFO - Successfully installed mozsystemmonitor-0.3
03:05:15 INFO - Return code: 0
03:05:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:05:15 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')]}
03:05:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:15 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')]}
03:05:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:15 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:15 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
03:05:15 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
03:05:15 INFO - Using env: {'DISPLAY': ':0',
03:05:15 INFO - 'HOME': '/home/cltbld',
03:05:15 INFO - 'LANG': 'en_US.UTF-8',
03:05:15 INFO - 'LANGUAGE': 'en_US:en',
03:05:15 INFO - 'LOGNAME': 'cltbld',
03:05:15 INFO - 'MAIL': '/var/mail/cltbld',
03:05:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:15 INFO - 'MOZ_NO_REMOTE': '1',
03:05:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:15 INFO - 'NO_EM_RESTART': '1',
03:05:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:15 INFO - 'PWD': '/builds/slave/test',
03:05:15 INFO - 'SHELL': '/bin/bash',
03:05:15 INFO - 'SHLVL': '1',
03:05:15 INFO - 'TERM': 'linux',
03:05:15 INFO - 'TMOUT': '86400',
03:05:15 INFO - 'USER': 'cltbld',
03:05:15 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:15 INFO - '_': '/tools/buildbot/bin/python'}
03:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:15 INFO - Collecting jsonschema==2.5.1
03:05:16 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:05:16 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:05:17 INFO - Installing collected packages: functools32, jsonschema
03:05:17 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:05:18 INFO - Return code: 0
03:05:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:05:18 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')]}
03:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:18 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')]}
03:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:18 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:18 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
03:05:18 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
03:05:18 INFO - Using env: {'DISPLAY': ':0',
03:05:18 INFO - 'HOME': '/home/cltbld',
03:05:18 INFO - 'LANG': 'en_US.UTF-8',
03:05:18 INFO - 'LANGUAGE': 'en_US:en',
03:05:18 INFO - 'LOGNAME': 'cltbld',
03:05:18 INFO - 'MAIL': '/var/mail/cltbld',
03:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:18 INFO - 'MOZ_NO_REMOTE': '1',
03:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:18 INFO - 'NO_EM_RESTART': '1',
03:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:18 INFO - 'PWD': '/builds/slave/test',
03:05:18 INFO - 'SHELL': '/bin/bash',
03:05:18 INFO - 'SHLVL': '1',
03:05:18 INFO - 'TERM': 'linux',
03:05:18 INFO - 'TMOUT': '86400',
03:05:18 INFO - 'USER': 'cltbld',
03:05:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:18 INFO - '_': '/tools/buildbot/bin/python'}
03:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:05:18 INFO - Return code: 0
03:05:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:05:18 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')]}
03:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:18 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')]}
03:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:18 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:18 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
03:05:18 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
03:05:18 INFO - Using env: {'DISPLAY': ':0',
03:05:18 INFO - 'HOME': '/home/cltbld',
03:05:18 INFO - 'LANG': 'en_US.UTF-8',
03:05:18 INFO - 'LANGUAGE': 'en_US:en',
03:05:18 INFO - 'LOGNAME': 'cltbld',
03:05:18 INFO - 'MAIL': '/var/mail/cltbld',
03:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:18 INFO - 'MOZ_NO_REMOTE': '1',
03:05:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:18 INFO - 'NO_EM_RESTART': '1',
03:05:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:18 INFO - 'PWD': '/builds/slave/test',
03:05:18 INFO - 'SHELL': '/bin/bash',
03:05:18 INFO - 'SHLVL': '1',
03:05:18 INFO - 'TERM': 'linux',
03:05:18 INFO - 'TMOUT': '86400',
03:05:18 INFO - 'USER': 'cltbld',
03:05:18 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:18 INFO - '_': '/tools/buildbot/bin/python'}
03:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:18 INFO - Collecting blobuploader==1.2.4
03:05:20 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:05:21 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:05:22 INFO - Installing collected packages: requests, docopt, blobuploader
03:05:22 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:05:22 INFO - Return code: 0
03:05:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:05:22 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')]}
03:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:22 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')]}
03:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:22 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:22 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
03:05:22 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
03:05:22 INFO - Using env: {'DISPLAY': ':0',
03:05:22 INFO - 'HOME': '/home/cltbld',
03:05:22 INFO - 'LANG': 'en_US.UTF-8',
03:05:22 INFO - 'LANGUAGE': 'en_US:en',
03:05:22 INFO - 'LOGNAME': 'cltbld',
03:05:22 INFO - 'MAIL': '/var/mail/cltbld',
03:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:22 INFO - 'MOZ_NO_REMOTE': '1',
03:05:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:22 INFO - 'NO_EM_RESTART': '1',
03:05:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:22 INFO - 'PWD': '/builds/slave/test',
03:05:22 INFO - 'SHELL': '/bin/bash',
03:05:22 INFO - 'SHLVL': '1',
03:05:22 INFO - 'TERM': 'linux',
03:05:22 INFO - 'TMOUT': '86400',
03:05:22 INFO - 'USER': 'cltbld',
03:05:22 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:22 INFO - '_': '/tools/buildbot/bin/python'}
03:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:22 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:05:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:05:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:05:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:05:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:05:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:05:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:05:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:05:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:05:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:05:24 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:05:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:05:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:05:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:05:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:05:25 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:05:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:05:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:05:26 INFO - Running setup.py install for manifestparser: started
03:05:26 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:05:26 INFO - Running setup.py install for mozcrash: started
03:05:26 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:05:26 INFO - Running setup.py install for mozdebug: started
03:05:26 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:05:26 INFO - Running setup.py install for mozdevice: started
03:05:27 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:05:27 INFO - Running setup.py install for mozfile: started
03:05:27 INFO - Running setup.py install for mozfile: finished with status 'done'
03:05:27 INFO - Running setup.py install for mozhttpd: started
03:05:27 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:05:27 INFO - Running setup.py install for mozinfo: started
03:05:27 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:05:27 INFO - Running setup.py install for mozInstall: started
03:05:27 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:05:27 INFO - Running setup.py install for mozleak: started
03:05:28 INFO - Running setup.py install for mozleak: finished with status 'done'
03:05:28 INFO - Running setup.py install for mozlog: started
03:05:28 INFO - Running setup.py install for mozlog: finished with status 'done'
03:05:28 INFO - Running setup.py install for moznetwork: started
03:05:28 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:05:28 INFO - Running setup.py install for mozprocess: started
03:05:28 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:05:28 INFO - Running setup.py install for mozprofile: started
03:05:28 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:05:29 INFO - Running setup.py install for mozrunner: started
03:05:29 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:05:29 INFO - Running setup.py install for mozscreenshot: started
03:05:29 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:05:29 INFO - Running setup.py install for moztest: started
03:05:29 INFO - Running setup.py install for moztest: finished with status 'done'
03:05:29 INFO - Running setup.py install for mozversion: started
03:05:29 INFO - Running setup.py install for mozversion: finished with status 'done'
03:05:30 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
03:05:30 INFO - Return code: 0
03:05:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:05:30 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')]}
03:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:30 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')]}
03:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:30 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:30 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
03:05:30 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
03:05:30 INFO - Using env: {'DISPLAY': ':0',
03:05:30 INFO - 'HOME': '/home/cltbld',
03:05:30 INFO - 'LANG': 'en_US.UTF-8',
03:05:30 INFO - 'LANGUAGE': 'en_US:en',
03:05:30 INFO - 'LOGNAME': 'cltbld',
03:05:30 INFO - 'MAIL': '/var/mail/cltbld',
03:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:30 INFO - 'MOZ_NO_REMOTE': '1',
03:05:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:30 INFO - 'NO_EM_RESTART': '1',
03:05:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:30 INFO - 'PWD': '/builds/slave/test',
03:05:30 INFO - 'SHELL': '/bin/bash',
03:05:30 INFO - 'SHLVL': '1',
03:05:30 INFO - 'TERM': 'linux',
03:05:30 INFO - 'TMOUT': '86400',
03:05:30 INFO - 'USER': 'cltbld',
03:05:30 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:30 INFO - '_': '/tools/buildbot/bin/python'}
03:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:30 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:05:30 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))
03:05:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:05:31 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))
03:05:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:05:31 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))
03:05:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:05:31 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))
03:05:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:05:31 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))
03:05:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:05:31 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))
03:05:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:05:32 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))
03:05:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:05:32 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))
03:05:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:05:32 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))
03:05:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:05:32 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))
03:05:32 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:05:32 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))
03:05:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:05:33 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))
03:05:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:05:33 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))
03:05:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:05:33 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))
03:05:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:05:33 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))
03:05:33 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:05:33 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))
03:05:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:05:34 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))
03:05:34 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:05:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
03:05:35 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))
03:05:35 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))
03:05:35 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))
03:05:35 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))
03:05:35 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))
03:05:35 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))
03:05:35 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:05:36 INFO - Installing collected packages: six, blessings
03:05:36 INFO - Successfully installed blessings-1.6 six-1.10.0
03:05:36 INFO - Return code: 0
03:05:36 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:05:37 INFO - Reading from file tmpfile_stdout
03:05:37 INFO - Reading from file tmpfile_stderr
03:05:37 INFO - Current package versions:
03:05:37 INFO - blessings == 1.6
03:05:37 INFO - blobuploader == 1.2.4
03:05:37 INFO - docopt == 0.6.1
03:05:37 INFO - functools32 == 3.2.3.post2
03:05:37 INFO - jsonschema == 2.5.1
03:05:37 INFO - manifestparser == 1.2
03:05:37 INFO - mozInstall == 1.14
03:05:37 INFO - mozcrash == 1.0
03:05:37 INFO - mozdebug == 0.1
03:05:37 INFO - mozdevice == 0.51
03:05:37 INFO - mozfile == 1.2
03:05:37 INFO - mozhttpd == 0.7
03:05:37 INFO - mozinfo == 0.10
03:05:37 INFO - mozleak == 0.1
03:05:37 INFO - mozlog == 3.5
03:05:37 INFO - moznetwork == 0.27
03:05:37 INFO - mozprocess == 0.25
03:05:37 INFO - mozprofile == 0.29
03:05:37 INFO - mozrunner == 6.13
03:05:37 INFO - mozscreenshot == 0.1
03:05:37 INFO - mozsystemmonitor == 0.3
03:05:37 INFO - moztest == 0.8
03:05:37 INFO - mozversion == 1.4
03:05:37 INFO - psutil == 3.1.1
03:05:37 INFO - requests == 1.2.3
03:05:37 INFO - six == 1.10.0
03:05:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:05:37 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')]}
03:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:37 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')]}
03:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:37 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:37 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
03:05:37 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
03:05:37 INFO - Using env: {'DISPLAY': ':0',
03:05:37 INFO - 'HOME': '/home/cltbld',
03:05:37 INFO - 'LANG': 'en_US.UTF-8',
03:05:37 INFO - 'LANGUAGE': 'en_US:en',
03:05:37 INFO - 'LOGNAME': 'cltbld',
03:05:37 INFO - 'MAIL': '/var/mail/cltbld',
03:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:37 INFO - 'MOZ_NO_REMOTE': '1',
03:05:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:37 INFO - 'NO_EM_RESTART': '1',
03:05:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:37 INFO - 'PWD': '/builds/slave/test',
03:05:37 INFO - 'SHELL': '/bin/bash',
03:05:37 INFO - 'SHLVL': '1',
03:05:37 INFO - 'TERM': 'linux',
03:05:37 INFO - 'TMOUT': '86400',
03:05:37 INFO - 'USER': 'cltbld',
03:05:37 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:37 INFO - '_': '/tools/buildbot/bin/python'}
03:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:37 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))
03:05:37 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))
03:05:37 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))
03:05:37 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))
03:05:37 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))
03:05:37 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))
03:05:37 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))
03:05:37 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))
03:05:37 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))
03:05:37 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:05:38 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:05:39 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
03:05:39 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))
03:05:39 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))
03:05:39 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))
03:05:39 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))
03:05:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
03:05:40 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:05:41 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
03:05:41 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:05:42 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
03:05:42 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:05:43 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
03:05:43 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
03:05:44 INFO - Found existing installation: requests 1.2.3
03:05:44 INFO - Uninstalling requests-1.2.3:
03:05:44 INFO - Successfully uninstalled requests-1.2.3
03:05:44 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
03:05:44 INFO - Return code: 0
03:05:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:05:44 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')]}
03:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:05:44 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')]}
03:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:05:44 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 0x20b7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2096a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22381a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, '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': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855', '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
03:05:44 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
03:05:44 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
03:05:44 INFO - Using env: {'DISPLAY': ':0',
03:05:44 INFO - 'HOME': '/home/cltbld',
03:05:44 INFO - 'LANG': 'en_US.UTF-8',
03:05:44 INFO - 'LANGUAGE': 'en_US:en',
03:05:44 INFO - 'LOGNAME': 'cltbld',
03:05:44 INFO - 'MAIL': '/var/mail/cltbld',
03:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:44 INFO - 'MOZ_NO_REMOTE': '1',
03:05:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:44 INFO - 'NO_EM_RESTART': '1',
03:05:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:44 INFO - 'PWD': '/builds/slave/test',
03:05:44 INFO - 'SHELL': '/bin/bash',
03:05:44 INFO - 'SHLVL': '1',
03:05:44 INFO - 'TERM': 'linux',
03:05:44 INFO - 'TMOUT': '86400',
03:05:44 INFO - 'USER': 'cltbld',
03:05:44 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:44 INFO - '_': '/tools/buildbot/bin/python'}
03:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple
03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:05:44 INFO - Return code: 0
03:05:44 INFO - Running post-action listener: _resource_record_post_action
03:05:44 INFO - Running post-action listener: _start_resource_monitoring
03:05:44 INFO - Starting resource monitoring.
03:05:44 INFO - [mozharness: 2017-11-25 11:05:44.844526Z] Finished create-virtualenv step (success)
03:05:44 INFO - [mozharness: 2017-11-25 11:05:44.845027Z] Running install step.
03:05:44 INFO - Running pre-action listener: _resource_record_pre_action
03:05:44 INFO - Running main action method: install
03:05:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:05:45 INFO - Reading from file tmpfile_stdout
03:05:45 INFO - Reading from file tmpfile_stderr
03:05:45 INFO - Detecting whether we're running mozinstall >=1.0...
03:05:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:05:45 INFO - Reading from file tmpfile_stdout
03:05:45 INFO - Output received:
03:05:45 INFO - Usage: mozinstall [options] installer
03:05:45 INFO - Options:
03:05:45 INFO - -h, --help show this help message and exit
03:05:45 INFO - -d DEST, --destination=DEST
03:05:45 INFO - Directory to install application into. [default:
03:05:45 INFO - "/builds/slave/test"]
03:05:45 INFO - --app=APP Application being installed. [default: firefox]
03:05:45 INFO - mkdir: /builds/slave/test/build/application
03:05:45 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']
03:05:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:06:03 INFO - Reading from file tmpfile_stdout
03:06:03 INFO - Output received:
03:06:03 INFO - /builds/slave/test/build/application/firefox/firefox
03:06:03 INFO - Running post-action listener: _resource_record_post_action
03:06:03 INFO - [mozharness: 2017-11-25 11:06:03.861869Z] Finished install step (success)
03:06:03 INFO - [mozharness: 2017-11-25 11:06:03.862013Z] Running setup-mitmproxy step.
03:06:03 INFO - Running pre-action listener: _resource_record_pre_action
03:06:03 INFO - Running main action method: setup_mitmproxy
03:06:03 INFO - Skipping: mitmproxy is not required
03:06:03 INFO - Running post-action listener: _resource_record_post_action
03:06:03 INFO - [mozharness: 2017-11-25 11:06:03.863434Z] Finished setup-mitmproxy step (success)
03:06:03 INFO - [mozharness: 2017-11-25 11:06:03.863774Z] Running run-tests step.
03:06:03 INFO - Running pre-action listener: _resource_record_pre_action
03:06:03 INFO - Running pre-action listener: _set_gcov_prefix
03:06:03 INFO - Running main action method: run_tests
03:06:03 WARNING - Try message not found.
03:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:06:03 INFO - Python 2.7.3
03:06:03 INFO - Return code: 0
03:06:03 INFO - grabbing minidump binary from tooltool
03:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:06:03 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 0x2235320>, '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 0x2236220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x22366d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:06:03 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
03:06:03 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
03:06:03 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
03:06:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:06:04 INFO - Return code: 0
03:06:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:06:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:06:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:06:04 INFO - ENV: RUST_BACKTRACE is now full
03:06:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:06:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:06:04 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/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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
03:06:04 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/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-002 --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
03:06:04 INFO - Using env: {'DISPLAY': ':0',
03:06:04 INFO - 'HOME': '/home/cltbld',
03:06:04 INFO - 'LANG': 'en_US.UTF-8',
03:06:04 INFO - 'LANGUAGE': 'en_US:en',
03:06:04 INFO - 'LOGNAME': 'cltbld',
03:06:04 INFO - 'MAIL': '/var/mail/cltbld',
03:06:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:06:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:06:04 INFO - 'MOZ_ACCELERATED': '1',
03:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:06:04 INFO - 'MOZ_NO_REMOTE': '1',
03:06:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:06:04 INFO - 'MOZ_WEBRENDER': '1',
03:06:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:06:04 INFO - 'NO_EM_RESTART': '1',
03:06:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:06:04 INFO - 'PWD': '/builds/slave/test',
03:06:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:06:04 INFO - 'RUST_BACKTRACE': 'full',
03:06:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:06:04 INFO - 'SHELL': '/bin/bash',
03:06:04 INFO - 'SHLVL': '1',
03:06:04 INFO - 'TERM': 'linux',
03:06:04 INFO - 'TMOUT': '86400',
03:06:04 INFO - 'USER': 'cltbld',
03:06:04 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:06:04 INFO - '_': '/tools/buildbot/bin/python'}
03:06:04 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/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-002', '--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
03:06:04 INFO - mozversion application_buildid: 20171125100431
03:06:04 INFO - mozversion application_changeset: 890ae45c3d15d5d86a62dc418fac22a4e49f8751
03:06:04 INFO - mozversion application_display_name: Nightly
03:06:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:06:04 INFO - mozversion application_name: Firefox
03:06:04 INFO - mozversion application_remotingname: firefox
03:06:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:06:04 INFO - mozversion application_vendor: Mozilla
03:06:04 INFO - mozversion application_version: 59.0a1
03:06:04 INFO - mozversion platform_buildid: 20171125100431
03:06:04 INFO - mozversion platform_changeset: 890ae45c3d15d5d86a62dc418fac22a4e49f8751
03:06:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:06:04 INFO - mozversion platform_version: 59.0a1
03:06:04 INFO - using testdate: 1511607964
03:06:04 INFO - actual date: 1511607964
03:06:04 INFO - starting webserver on 'localhost:50946'
03:06:04 INFO - SUITE-START | Running 3 tests
03:06:04 INFO - TEST-START | basic_compositor_video
03:06:04 INFO - Initialising browser for basic_compositor_video test...
03:06:04 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:06:04 INFO - Installing Add-ons
03:06:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50946/getInfo.html -profile /tmp/tmpIn7L6k/profile
03:06:04 INFO - TEST-INFO | started process 3916 (/builds/slave/test/build/application/firefox/firefox http://localhost:50946/getInfo.html)
03:06:20 INFO - TEST-INFO | 3916: exit 0
03:06:20 INFO - Browser initialized.
03:06:20 INFO - Running cycle 1/1 for basic_compositor_video test...
03:06:20 INFO - Using env: {'DISPLAY': ':0',
03:06:20 INFO - 'HOME': '/home/cltbld',
03:06:20 INFO - 'JSGC_DISABLE_POISONING': '1',
03:06:20 INFO - 'LANG': 'en_US.UTF-8',
03:06:20 INFO - 'LANGUAGE': 'en_US:en',
03:06:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:06:20 INFO - 'LOGNAME': 'cltbld',
03:06:20 INFO - 'MAIL': '/var/mail/cltbld',
03:06:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:06:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:06:20 INFO - 'MOZ_ACCELERATED': '1',
03:06:20 INFO - 'MOZ_CRASHREPORTER': '1',
03:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:06:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:06:20 INFO - 'MOZ_NO_REMOTE': '1',
03:06:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:06:20 INFO - 'MOZ_WEBRENDER': '1',
03:06:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:06:20 INFO - 'NO_EM_RESTART': '1',
03:06:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:06:20 INFO - 'PWD': '/builds/slave/test',
03:06:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:06:20 INFO - 'RUST_BACKTRACE': 'full',
03:06:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:06:20 INFO - 'SHELL': '/bin/bash',
03:06:20 INFO - 'SHLVL': '1',
03:06:20 INFO - 'TERM': 'linux',
03:06:20 INFO - 'TMOUT': '86400',
03:06:20 INFO - 'USER': 'cltbld',
03:06:20 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:06:20 INFO - '_': '/tools/buildbot/bin/python'}
03:06:20 INFO - TEST-INFO | started process 4218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIn7L6k/profile)
03:06:21 INFO - PID 4218 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:06:21 INFO - PID 4218 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:06:22 INFO - PID 4218 |
03:06:22 INFO - PID 4218 | (/builds/slave/test/build/application/firefox/firefox:4280): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:06:22 INFO - PID 4218 |
03:06:23 INFO - PID 4218 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:07:08 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.645939153439153 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.472688504326329 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.52122268907563 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.510552070263489 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0971155830753347 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0684585889570557 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.725716535433068 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.556708428246015 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.250538243626064 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.177306889352819 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.695758998435055 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.69484741784037 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.07646226415094 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759949324324324 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.994324009324015 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.803197278911562 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.009836448598128 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780779661016955 ms/frame
03:07:08 INFO - PID 4218 |
03:07:08 INFO - PID 4218 | Cycle 1(1): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:07:54 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.490058091286307 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4641502463054183 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5218571428571432 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5099121706399004 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0934072164948447 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0725268817204303 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.139248275862071 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.1091067761806945 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.232207334273627 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.192746331236894 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.695672926447574 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.598172413793099 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.993065268065272 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.757668918918908 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010455607476632 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.782203389830508 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.9626102088166935 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.7134060402684606 ms/frame
03:07:54 INFO - PID 4218 |
03:07:54 INFO - PID 4218 | Cycle 1(2): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:08:40 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.489871369294606 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460977859778598 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5232548359966356 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4979962546816474 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0810626283367557 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0725960061443938 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.219416315049226 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.158627858627857 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.3869298245614035 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.358616557734204 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.680826833073323 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.729503546099286 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.993275058275054 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.713775167785241 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.0269672131147525 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.757415540540539 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.9997202797202736 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.781915254237291 ms/frame
03:08:40 INFO - PID 4218 |
03:08:40 INFO - PID 4218 | Cycle 1(3): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:09:26 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.479273327828241 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460325953259533 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5150502933780383 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.506384711779449 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0772153846153847 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0771923076923096 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.184644351464434 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.159948024948028 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.525754147812967 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.416501103752766 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.6446207430340545 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673212616822426 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.961589327146163 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.7856779661017 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.009789719626173 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.780254237288146 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.928614318706706 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.75773648648649 ms/frame
03:09:26 INFO - PID 4218 |
03:09:26 INFO - PID 4218 | Cycle 1(4): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:10:12 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.477667217175888 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4725278121137206 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5134547738693462 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.513668341708543 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.086764403292182 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0777538461538447 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.208948106591865 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.167562500000001 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.225323943661971 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.185575313807535 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.799903999999998 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.831316425120775 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.010642523364478 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.803996598639456 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9943589743589785 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.736632996632992 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.97754651162791 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.757719594594606 ms/frame
03:10:12 INFO - PID 4218 |
03:10:12 INFO - PID 4218 | Cycle 1(5): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:10:58 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4840397350993375 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.463134236453202 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.513458961474037 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5069298245614045 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.083227132579652 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.067730061349693 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.232306064880115 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.228964059196616 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.232080394922429 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.203182773109244 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.68078003120125 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673037383177564 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.961728538283065 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.782813559322035 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.995104895104898 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.783016949152535 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.010116822429894 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.758006756756766 ms/frame
03:10:58 INFO - PID 4218 |
03:10:58 INFO - PID 4218 | Cycle 1(6): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:11:44 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4715980230642502 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.467478421701603 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.525858585858586 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5065037593984956 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.089727085478888 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0680061349693255 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.1723713490959655 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.1408592132505175 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.226190140845072 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.17647181628393 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.688828124999998 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.640974477958242 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011693925233638 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781966101694916 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.026932084309133 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.756655405405409 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.999289044289044 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.758293918918925 ms/frame
03:11:44 INFO - PID 4218 |
03:11:44 INFO - PID 4218 | Cycle 1(7): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:12:29 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.475321782178218 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.470456790123457 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5150544844928753 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5070426065162907 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0838540596094552 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0779769230769225 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.262563920454547 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.166791666666669 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.274836182336184 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.212421052631582 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.666936236391913 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.630300925925928 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.060011764705876 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.734730639730652 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.027447306791572 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.780559322033897 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.9619025522041635 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780440677966112 ms/frame
03:12:29 INFO - PID 4218 |
03:12:29 INFO - PID 4218 | Cycle 1(8): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:13:15 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4796652892561983 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4733003708281838 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.506520467836257 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.506817042606517 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0906488156539655 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.073125960061446 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.739423380726701 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.556879271070609 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.386447368421055 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.397131868131868 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.710117739403455 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.7080705882352945 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.009509345794392 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.804965986394545 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.977686046511625 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.714714765100673 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.026334894613572 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.80537414965986 ms/frame
03:13:15 INFO - PID 4218 |
03:13:15 INFO - PID 4218 | Cycle 1(9): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:14:01 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4960524126455907 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.466479654747226 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.515058675607712 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5010687500000004 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.077379487179487 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0730337941628267 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.138006896551724 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.090725971370146 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.405947136563878 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.348641304347826 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.724259842519683 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.708117647058825 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.994615384615382 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.802687074829925 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.0264519906323315 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.780593220338964 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.011565420560741 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.758327702702715 ms/frame
03:14:01 INFO - PID 4218 |
03:14:01 INFO - PID 4218 | Cycle 1(10): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:14:47 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4754744224422445 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.47960966542751 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5213781512605045 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.506209273182956 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0962022703818364 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.078038461538462 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.15630886426593 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.11594650205761 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.214403089887644 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.184874476987446 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.71027472527473 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.662389277389271 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.993962703962704 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.734646464646472 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9945804195804095 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.713959731543631 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.979767441860455 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.757854729729744 ms/frame
03:14:47 INFO - PID 4218 |
03:14:47 INFO - PID 4218 | Cycle 1(11): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:15:33 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_startup = 2.491931063122924 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4662083847102347 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5257070707070706 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4973782771535578 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_startup = 3.093000000000001 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.067515337423311 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_startup = 4.202016806722689 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1_inclip = 4.149585062240661 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.226140845070424 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.177223382045928 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_startup = 4.658897515527953 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.480p.60fps.webm_scale_2_inclip = 4.706435294117648 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.993088578088569 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.831126279863495 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010654205607471 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.804030612244905 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.026744730679153 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.805391156462572 ms/frame
03:15:33 INFO - PID 4218 |
03:15:33 INFO - PID 4218 | Cycle 1(12): loaded http://localhost:50946/tests/video/video_playback.html (next: http://localhost:50946/tests/video/video_playback.html)
03:15:34 INFO - PID 4218 | __start_tp_report
03:15:34 INFO - PID 4218 | _x_x_mozilla_page_load
03:15:34 INFO - PID 4218 | _x_x_mozilla_page_load_details
03:15:34 INFO - PID 4218 | |i|pagename|runs|
03:15:34 INFO - PID 4218 | |0;240p.120fps.mp4_scale_1_startup;2.645939153439153;2.490058091286307;2.489871369294606;2.479273327828241;2.477667217175888;2.4840397350993375;2.4715980230642502;2.475321782178218;2.4796652892561983;2.4960524126455907;2.4754744224422445;2.491931063122924
03:15:34 INFO - PID 4218 | |1;240p.120fps.mp4_scale_1_inclip;2.472688504326329;2.4641502463054183;2.460977859778598;2.460325953259533;2.4725278121137206;2.463134236453202;2.467478421701603;2.470456790123457;2.4733003708281838;2.466479654747226;2.47960966542751;2.4662083847102347
03:15:34 INFO - PID 4218 | |2;240p.120fps.mp4_scale_1.1_startup;2.52122268907563;2.5218571428571432;2.5232548359966356;2.5150502933780383;2.5134547738693462;2.513458961474037;2.525858585858586;2.5150544844928753;2.506520467836257;2.515058675607712;2.5213781512605045;2.5257070707070706
03:15:34 INFO - PID 4218 | |3;240p.120fps.mp4_scale_1.1_inclip;2.510552070263489;2.5099121706399004;2.4979962546816474;2.506384711779449;2.513668341708543;2.5069298245614045;2.5065037593984956;2.5070426065162907;2.506817042606517;2.5010687500000004;2.506209273182956;2.4973782771535578
03:15:34 INFO - PID 4218 | |4;240p.120fps.mp4_scale_2_startup;3.0971155830753347;3.0934072164948447;3.0810626283367557;3.0772153846153847;3.086764403292182;3.083227132579652;3.089727085478888;3.0838540596094552;3.0906488156539655;3.077379487179487;3.0962022703818364;3.093000000000001
03:15:34 INFO - PID 4218 | |5;240p.120fps.mp4_scale_2_inclip;3.0684585889570557;3.0725268817204303;3.0725960061443938;3.0771923076923096;3.0777538461538447;3.067730061349693;3.0680061349693255;3.0779769230769225;3.073125960061446;3.0730337941628267;3.078038461538462;3.067515337423311
03:15:34 INFO - PID 4218 | |6;480p.60fps.webm_scale_1_startup;4.725716535433068;4.139248275862071;4.219416315049226;4.184644351464434;4.208948106591865;4.232306064880115;4.1723713490959655;4.262563920454547;4.739423380726701;4.138006896551724;4.15630886426593;4.202016806722689
03:15:34 INFO - PID 4218 | |7;480p.60fps.webm_scale_1_inclip;4.556708428246015;4.1091067761806945;4.158627858627857;4.159948024948028;4.167562500000001;4.228964059196616;4.1408592132505175;4.166791666666669;4.556879271070609;4.090725971370146;4.11594650205761;4.149585062240661
03:15:34 INFO - PID 4218 | |8;480p.60fps.webm_scale_1.1_startup;4.250538243626064;4.232207334273627;4.3869298245614035;4.525754147812967;4.225323943661971;4.232080394922429;4.226190140845072;4.274836182336184;4.386447368421055;4.405947136563878;4.214403089887644;4.226140845070424
03:15:34 INFO - PID 4218 | |9;480p.60fps.webm_scale_1.1_inclip;4.177306889352819;4.192746331236894;4.358616557734204;4.416501103752766;4.185575313807535;4.203182773109244;4.17647181628393;4.212421052631582;4.397131868131868;4.348641304347826;4.184874476987446;4.177223382045928
03:15:34 INFO - PID 4218 | |10;480p.60fps.webm_scale_2_startup;4.695758998435055;4.695672926447574;4.680826833073323;4.6446207430340545;4.799903999999998;4.68078003120125;4.688828124999998;4.666936236391913;4.710117739403455;4.724259842519683;4.71027472527473;4.658897515527953
03:15:34 INFO - PID 4218 | |11;480p.60fps.webm_scale_2_inclip;4.69484741784037;4.598172413793099;4.729503546099286;4.673212616822426;4.831316425120775;4.673037383177564;4.640974477958242;4.630300925925928;4.7080705882352945;4.708117647058825;4.662389277389271;4.706435294117648
03:15:34 INFO - PID 4218 | |12;1080p.60fps.mp4_scale_1_startup;7.07646226415094;6.993065268065272;6.993275058275054;6.961589327146163;7.010642523364478;6.961728538283065;7.011693925233638;7.060011764705876;7.009509345794392;6.994615384615382;6.993962703962704;6.993088578088569
03:15:34 INFO - PID 4218 | |13;1080p.60fps.mp4_scale_1_inclip;6.759949324324324;6.757668918918908;6.713775167785241;6.7856779661017;6.803996598639456;6.782813559322035;6.781966101694916;6.734730639730652;6.804965986394545;6.802687074829925;6.734646464646472;6.831126279863495
03:15:34 INFO - PID 4218 | |14;1080p.60fps.mp4_scale_1.1_startup;6.994324009324015;7.010455607476632;7.0269672131147525;7.009789719626173;6.9943589743589785;6.995104895104898;7.026932084309133;7.027447306791572;6.977686046511625;7.0264519906323315;6.9945804195804095;7.010654205607471
03:15:34 INFO - PID 4218 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.803197278911562;6.782203389830508;6.757415540540539;6.780254237288146;6.736632996632992;6.783016949152535;6.756655405405409;6.780559322033897;6.714714765100673;6.780593220338964;6.713959731543631;6.804030612244905
03:15:34 INFO - PID 4218 | |16;1080p.60fps.mp4_scale_2_startup;7.009836448598128;6.9626102088166935;6.9997202797202736;6.928614318706706;6.97754651162791;7.010116822429894;6.999289044289044;6.9619025522041635;7.026334894613572;7.011565420560741;6.979767441860455;7.026744730679153
03:15:34 INFO - PID 4218 | |17;1080p.60fps.mp4_scale_2_inclip;6.780779661016955;6.7134060402684606;6.781915254237291;6.75773648648649;6.757719594594606;6.758006756756766;6.758293918918925;6.780440677966112;6.80537414965986;6.758327702702715;6.757854729729744;6.805391156462572
03:15:34 INFO - PID 4218 | __end_tp_report
03:15:34 INFO - PID 4218 | __start_cc_report
03:15:34 INFO - PID 4218 | _x_x_mozilla_cycle_collect,609
03:15:34 INFO - PID 4218 | __end_cc_report
03:15:34 INFO - PID 4218 | __startTimestamp1511608534128__endTimestamp
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | ------- Summary: start -------
03:15:34 INFO - PID 4218 | Number of tests: 18
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.50 Median:2.49 stddev:0.05 (1.9%) stddev-sans-first:0.01
03:15:34 INFO - PID 4218 | Values: 2.6 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.47 Median:2.47 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:15:34 INFO - PID 4218 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.52 Median:2.52 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:15:34 INFO - PID 4218 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.51 Median:2.51 stddev:0.00 (0.2%) stddev-sans-first:0.00
03:15:34 INFO - PID 4218 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.09 Median:3.09 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:15:34 INFO - PID 4218 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.07 Median:3.07 stddev:0.00 (0.1%) stddev-sans-first:0.00
03:15:34 INFO - PID 4218 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.28 Median:4.21 stddev:0.21 (5.1%) stddev-sans-first:0.17
03:15:34 INFO - PID 4218 | Values: 4.7 4.1 4.2 4.2 4.2 4.2 4.2 4.3 4.7 4.1 4.2 4.2
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.22 Median:4.16 stddev:0.16 (3.9%) stddev-sans-first:0.13
03:15:34 INFO - PID 4218 | Values: 4.6 4.1 4.2 4.2 4.2 4.2 4.1 4.2 4.6 4.1 4.1 4.1
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.30 Median:4.26 stddev:0.10 (2.4%) stddev-sans-first:0.11
03:15:34 INFO - PID 4218 | Values: 4.3 4.2 4.4 4.5 4.2 4.2 4.2 4.3 4.4 4.4 4.2 4.2
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.25 Median:4.21 stddev:0.10 (2.3%) stddev-sans-first:0.10
03:15:34 INFO - PID 4218 | Values: 4.2 4.2 4.4 4.4 4.2 4.2 4.2 4.2 4.4 4.3 4.2 4.2
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.70 Median:4.70 stddev:0.04 (0.8%) stddev-sans-first:0.04
03:15:34 INFO - PID 4218 | Values: 4.7 4.7 4.7 4.6 4.8 4.7 4.7 4.7 4.7 4.7 4.7 4.7
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.69 Median:4.70 stddev:0.06 (1.3%) stddev-sans-first:0.06
03:15:34 INFO - PID 4218 | Values: 4.7 4.6 4.7 4.7 4.8 4.7 4.6 4.6 4.7 4.7 4.7 4.7
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.00 Median:7.00 stddev:0.03 (0.5%) stddev-sans-first:0.03
03:15:34 INFO - PID 4218 | Values: 7.1 7.0 7.0 7.0 7.0 7.0 7.0 7.1 7.0 7.0 7.0 7.0
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.77 Median:6.78 stddev:0.03 (0.5%) stddev-sans-first:0.04
03:15:34 INFO - PID 4218 | Values: 6.8 6.8 6.7 6.8 6.8 6.8 6.8 6.7 6.8 6.8 6.7 6.8
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.01 Median:7.01 stddev:0.02 (0.2%) stddev-sans-first:0.02
03:15:34 INFO - PID 4218 | Values: 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.77 Median:6.78 stddev:0.03 (0.5%) stddev-sans-first:0.03
03:15:34 INFO - PID 4218 | Values: 6.8 6.8 6.8 6.8 6.7 6.8 6.8 6.8 6.7 6.8 6.7 6.8
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:6.99 Median:7.00 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:15:34 INFO - PID 4218 | Values: 7.0 7.0 7.0 6.9 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
03:15:34 INFO - PID 4218 |
03:15:34 INFO - PID 4218 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.77 Median:6.77 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:15:34 INFO - PID 4218 | Values: 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8
03:15:34 INFO - PID 4218 | -------- Summary: end --------
03:15:34 INFO - PID 4218 |
03:15:39 INFO - TEST-INFO | 4218: exit 0
03:15:39 INFO - TEST-OK | basic_compositor_video | took 575494ms
03:15:39 INFO - TEST-START | glvideo
03:15:39 INFO - Initialising browser for glvideo test...
03:15:39 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:15:39 INFO - Installing Add-ons
03:15:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50946/getInfo.html -profile /tmp/tmpwvh9zn/profile
03:15:39 INFO - TEST-INFO | started process 5494 (/builds/slave/test/build/application/firefox/firefox http://localhost:50946/getInfo.html)
03:15:51 INFO - TEST-INFO | 5494: exit 0
03:15:51 INFO - Browser initialized.
03:15:51 INFO - Running cycle 1/1 for glvideo test...
03:15:51 INFO - Using env: {'DISPLAY': ':0',
03:15:51 INFO - 'HOME': '/home/cltbld',
03:15:51 INFO - 'JSGC_DISABLE_POISONING': '1',
03:15:51 INFO - 'LANG': 'en_US.UTF-8',
03:15:51 INFO - 'LANGUAGE': 'en_US:en',
03:15:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:15:51 INFO - 'LOGNAME': 'cltbld',
03:15:51 INFO - 'MAIL': '/var/mail/cltbld',
03:15:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:15:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:15:51 INFO - 'MOZ_ACCELERATED': '1',
03:15:51 INFO - 'MOZ_CRASHREPORTER': '1',
03:15:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:15:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:15:51 INFO - 'MOZ_NO_REMOTE': '1',
03:15:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:15:51 INFO - 'MOZ_WEBRENDER': '1',
03:15:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:15:51 INFO - 'NO_EM_RESTART': '1',
03:15:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:15:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:15:51 INFO - 'PWD': '/builds/slave/test',
03:15:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:15:51 INFO - 'RUST_BACKTRACE': 'full',
03:15:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:15:51 INFO - 'SHELL': '/bin/bash',
03:15:51 INFO - 'SHLVL': '1',
03:15:51 INFO - 'TERM': 'linux',
03:15:51 INFO - 'TMOUT': '86400',
03:15:51 INFO - 'USER': 'cltbld',
03:15:51 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:15:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:15:51 INFO - '_': '/tools/buildbot/bin/python'}
03:15:51 INFO - TEST-INFO | started process 5838 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwvh9zn/profile)
03:15:52 INFO - PID 5838 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:15:52 INFO - PID 5838 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:15:52 INFO - PID 5838 |
03:15:52 INFO - PID 5838 | (/builds/slave/test/build/application/firefox/firefox:5899): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:15:52 INFO - PID 5838 |
03:15:54 INFO - PID 5838 | [talos glvideo result] Mean tick time across 100 ticks: 6.8378000000000005 ms
03:15:54 INFO - PID 5838 | Cycle 1(1): loaded http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html)
03:15:54 INFO - PID 5838 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:15:55 INFO - PID 5838 | [talos glvideo result] Mean tick time across 100 ticks: 6.56215 ms
03:15:55 INFO - PID 5838 | Cycle 1(2): loaded http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html)
03:15:56 INFO - PID 5838 | [talos glvideo result] Mean tick time across 100 ticks: 6.48505 ms
03:15:56 INFO - PID 5838 | Cycle 1(3): loaded http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html)
03:15:58 INFO - PID 5838 | [talos glvideo result] Mean tick time across 100 ticks: 6.6488499999999995 ms
03:15:58 INFO - PID 5838 | Cycle 1(4): loaded http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html)
03:15:59 INFO - PID 5838 | [talos glvideo result] Mean tick time across 100 ticks: 6.63195 ms
03:15:59 INFO - PID 5838 | Cycle 1(5): loaded http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50946/tests/webgl/benchmarks/video/video_upload.html)
03:15:59 INFO - PID 5838 | __start_tp_report
03:15:59 INFO - PID 5838 | _x_x_mozilla_page_load
03:15:59 INFO - PID 5838 | _x_x_mozilla_page_load_details
03:15:59 INFO - PID 5838 | |i|pagename|runs|
03:15:59 INFO - PID 5838 | |0;Mean tick time across 100 ticks: ;6.8378000000000005;6.56215;6.48505;6.6488499999999995;6.63195
03:15:59 INFO - PID 5838 | __end_tp_report
03:15:59 INFO - PID 5838 | __start_cc_report
03:15:59 INFO - PID 5838 | _x_x_mozilla_cycle_collect,268
03:15:59 INFO - PID 5838 | __end_cc_report
03:15:59 INFO - PID 5838 | __startTimestamp1511608559674__endTimestamp
03:15:59 INFO - PID 5838 |
03:15:59 INFO - PID 5838 | ------- Summary: start -------
03:15:59 INFO - PID 5838 | Number of tests: 1
03:15:59 INFO - PID 5838 |
03:15:59 INFO - PID 5838 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.63 Median:6.63 stddev:0.13 (2.0%) stddev-sans-first:0.07
03:15:59 INFO - PID 5838 | Values: 6.8 6.6 6.5 6.6 6.6
03:15:59 INFO - PID 5838 | -------- Summary: end --------
03:15:59 INFO - PID 5838 |
03:16:00 INFO - PID 5838 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:16:05 INFO - TEST-INFO | 5838: exit 0
03:16:05 INFO - TEST-OK | glvideo | took 25598ms
03:16:05 INFO - TEST-START | displaylist_mutate
03:16:05 INFO - Initialising browser for displaylist_mutate test...
03:16:05 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:16:05 INFO - Installing Add-ons
03:16:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50946/getInfo.html -profile /tmp/tmpdKeJLO/profile
03:16:05 INFO - TEST-INFO | started process 6075 (/builds/slave/test/build/application/firefox/firefox http://localhost:50946/getInfo.html)
03:16:17 INFO - TEST-INFO | 6075: exit 0
03:16:17 INFO - Browser initialized.
03:16:17 INFO - Running cycle 1/1 for displaylist_mutate test...
03:16:17 INFO - Using env: {'DISPLAY': ':0',
03:16:17 INFO - 'HOME': '/home/cltbld',
03:16:17 INFO - 'JSGC_DISABLE_POISONING': '1',
03:16:17 INFO - 'LANG': 'en_US.UTF-8',
03:16:17 INFO - 'LANGUAGE': 'en_US:en',
03:16:17 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:16:17 INFO - 'LOGNAME': 'cltbld',
03:16:17 INFO - 'MAIL': '/var/mail/cltbld',
03:16:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:16:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:16:17 INFO - 'MOZ_ACCELERATED': '1',
03:16:17 INFO - 'MOZ_CRASHREPORTER': '1',
03:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:16:17 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:16:17 INFO - 'MOZ_NO_REMOTE': '1',
03:16:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:16:17 INFO - 'MOZ_WEBRENDER': '1',
03:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:16:17 INFO - 'NO_EM_RESTART': '1',
03:16:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:16:17 INFO - 'PWD': '/builds/slave/test',
03:16:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:16:17 INFO - 'RUST_BACKTRACE': 'full',
03:16:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:16:17 INFO - 'SHELL': '/bin/bash',
03:16:17 INFO - 'SHLVL': '1',
03:16:17 INFO - 'TERM': 'linux',
03:16:17 INFO - 'TMOUT': '86400',
03:16:17 INFO - 'USER': 'cltbld',
03:16:17 INFO - 'XDG_SESSION_COOKIE': 'a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855',
03:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:16:17 INFO - '_': '/tools/buildbot/bin/python'}
03:16:17 INFO - TEST-INFO | started process 6365 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdKeJLO/profile)
03:16:18 INFO - PID 6365 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:16:18 INFO - PID 6365 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:16:18 INFO - PID 6365 |
03:16:18 INFO - PID 6365 | (/builds/slave/test/build/application/firefox/firefox:6428): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:16:18 INFO - PID 6365 |
03:16:24 INFO - PID 6365 | Cycle 1(1): loaded http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html)
03:16:25 INFO - PID 6365 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:16:31 INFO - PID 6365 | Cycle 1(2): loaded http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html)
03:16:38 INFO - PID 6365 | Cycle 1(3): loaded http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html)
03:16:45 INFO - PID 6365 | Cycle 1(4): loaded http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html)
03:16:52 INFO - PID 6365 | Cycle 1(5): loaded http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50946/tests/layout/benchmarks/displaylist_mutate.html)
03:16:52 INFO - PID 6365 | __start_tp_report
03:16:52 INFO - PID 6365 | _x_x_mozilla_page_load
03:16:52 INFO - PID 6365 | _x_x_mozilla_page_load_details
03:16:52 INFO - PID 6365 | |i|pagename|runs|
03:16:52 INFO - PID 6365 | |0;/displaylist_mutate.html;5853.465;6019.665;6129.295;6071.530000000001;6133.92
03:16:52 INFO - PID 6365 | __end_tp_report
03:16:52 INFO - PID 6365 | __start_cc_report
03:16:52 INFO - PID 6365 | _x_x_mozilla_cycle_collect,263
03:16:52 INFO - PID 6365 | __end_cc_report
03:16:52 INFO - PID 6365 | __startTimestamp1511608612687__endTimestamp
03:16:52 INFO - PID 6365 |
03:16:52 INFO - PID 6365 | ------- Summary: start -------
03:16:52 INFO - PID 6365 | Number of tests: 1
03:16:52 INFO - PID 6365 |
03:16:52 INFO - PID 6365 | [#0] /displaylist_mutate.html Cycles:5 Average:6041.57 Median:6071.53 stddev:115.09 (1.9%) stddev-sans-first:54.02
03:16:52 INFO - PID 6365 | Values: 5853.5 6019.7 6129.3 6071.5 6133.9
03:16:52 INFO - PID 6365 | -------- Summary: end --------
03:16:52 INFO - PID 6365 |
03:16:53 INFO - PID 6365 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:16:58 INFO - TEST-INFO | 6365: exit 0
03:16:58 INFO - TEST-OK | displaylist_mutate | took 52938ms
03:16:58 INFO - SUITE-END | took 654s
03:16:58 INFO - Completed test suite (00:10:54)
03:16:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.375410784629278, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.645939153439153, 2.490058091286307, 2.489871369294606, 2.479273327828241, 2.477667217175888, 2.4840397350993375, 2.4715980230642502, 2.475321782178218, 2.4796652892561983, 2.4960524126455907, 2.4754744224422445, 2.491931063122924], "value": 2.4796652892561983, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.472688504326329, 2.4641502463054183, 2.460977859778598, 2.460325953259533, 2.4725278121137206, 2.463134236453202, 2.467478421701603, 2.470456790123457, 2.4733003708281838, 2.466479654747226, 2.47960966542751, 2.4662083847102347], "value": 2.466479654747226, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.52122268907563, 2.5218571428571432, 2.5232548359966356, 2.5150502933780383, 2.5134547738693462, 2.513458961474037, 2.525858585858586, 2.5150544844928753, 2.506520467836257, 2.515058675607712, 2.5213781512605045, 2.5257070707070706], "value": 2.515058675607712, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.510552070263489, 2.5099121706399004, 2.4979962546816474, 2.506384711779449, 2.513668341708543, 2.5069298245614045, 2.5065037593984956, 2.5070426065162907, 2.506817042606517, 2.5010687500000004, 2.506209273182956, 2.4973782771535578], "value": 2.5065037593984956, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0971155830753347, 3.0934072164948447, 3.0810626283367557, 3.0772153846153847, 3.086764403292182, 3.083227132579652, 3.089727085478888, 3.0838540596094552, 3.0906488156539655, 3.077379487179487, 3.0962022703818364, 3.093000000000001], "value": 3.086764403292182, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0684585889570557, 3.0725268817204303, 3.0725960061443938, 3.0771923076923096, 3.0777538461538447, 3.067730061349693, 3.0680061349693255, 3.0779769230769225, 3.073125960061446, 3.0730337941628267, 3.078038461538462, 3.067515337423311], "value": 3.0730337941628267, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.725716535433068, 4.139248275862071, 4.219416315049226, 4.184644351464434, 4.208948106591865, 4.232306064880115, 4.1723713490959655, 4.262563920454547, 4.739423380726701, 4.138006896551724, 4.15630886426593, 4.202016806722689], "value": 4.202016806722689, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.556708428246015, 4.1091067761806945, 4.158627858627857, 4.159948024948028, 4.167562500000001, 4.228964059196616, 4.1408592132505175, 4.166791666666669, 4.556879271070609, 4.090725971370146, 4.11594650205761, 4.149585062240661], "value": 4.158627858627857, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.250538243626064, 4.232207334273627, 4.3869298245614035, 4.525754147812967, 4.225323943661971, 4.232080394922429, 4.226190140845072, 4.274836182336184, 4.386447368421055, 4.405947136563878, 4.214403089887644, 4.226140845070424], "value": 4.232207334273627, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.177306889352819, 4.192746331236894, 4.358616557734204, 4.416501103752766, 4.185575313807535, 4.203182773109244, 4.17647181628393, 4.212421052631582, 4.397131868131868, 4.348641304347826, 4.184874476987446, 4.177223382045928], "value": 4.203182773109244, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.695758998435055, 4.695672926447574, 4.680826833073323, 4.6446207430340545, 4.799903999999998, 4.68078003120125, 4.688828124999998, 4.666936236391913, 4.710117739403455, 4.724259842519683, 4.71027472527473, 4.658897515527953], "value": 4.688828124999998, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.69484741784037, 4.598172413793099, 4.729503546099286, 4.673212616822426, 4.831316425120775, 4.673037383177564, 4.640974477958242, 4.630300925925928, 4.7080705882352945, 4.708117647058825, 4.662389277389271, 4.706435294117648], "value": 4.673212616822426, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.07646226415094, 6.993065268065272, 6.993275058275054, 6.961589327146163, 7.010642523364478, 6.961728538283065, 7.011693925233638, 7.060011764705876, 7.009509345794392, 6.994615384615382, 6.993962703962704, 6.993088578088569], "value": 6.993962703962704, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.759949324324324, 6.757668918918908, 6.713775167785241, 6.7856779661017, 6.803996598639456, 6.782813559322035, 6.781966101694916, 6.734730639730652, 6.804965986394545, 6.802687074829925, 6.734646464646472, 6.831126279863495], "value": 6.782813559322035, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.994324009324015, 7.010455607476632, 7.0269672131147525, 7.009789719626173, 6.9943589743589785, 6.995104895104898, 7.026932084309133, 7.027447306791572, 6.977686046511625, 7.0264519906323315, 6.9945804195804095, 7.010654205607471], "value": 7.010455607476632, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.803197278911562, 6.782203389830508, 6.757415540540539, 6.780254237288146, 6.736632996632992, 6.783016949152535, 6.756655405405409, 6.780559322033897, 6.714714765100673, 6.780593220338964, 6.713959731543631, 6.804030612244905], "value": 6.780254237288146, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.009836448598128, 6.9626102088166935, 6.9997202797202736, 6.928614318706706, 6.97754651162791, 7.010116822429894, 6.999289044289044, 6.9619025522041635, 7.026334894613572, 7.011565420560741, 6.979767441860455, 7.026744730679153], "value": 6.999289044289044, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.780779661016955, 6.7134060402684606, 6.781915254237291, 6.75773648648649, 6.757719594594606, 6.758006756756766, 6.758293918918925, 6.780440677966112, 6.80537414965986, 6.758327702702715, 6.757854729729744, 6.805391156462572], "value": 6.758293918918925, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.8378000000000005, 6.56215, 6.48505, 6.6488499999999995, 6.63195], "value": 6.597049999999999, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5853.465, 6019.665, 6129.295, 6071.530000000001, 6133.92], "value": 6100.4125, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
03:16:58 INFO - Return code: 0
03:16:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:16:58 INFO - # TBPL SUCCESS #
03:16:58 INFO - Running post-action listener: _package_coverage_data
03:16:58 INFO - Running post-action listener: _resource_record_post_action
03:16:58 INFO - [mozharness: 2017-11-25 11:16:58.754247Z] Finished run-tests step (success)
03:16:58 INFO - Running post-run listener: _resource_record_post_run
03:16:58 INFO - Total resource usage - Wall time: 674s; CPU: 43.0%; Read bytes: 21803008; Write bytes: 351571968; Read time: 8880; Write time: 341164
03:16:58 INFO - TinderboxPrint: CPU usage
43.4%
03:16:58 INFO - TinderboxPrint: I/O read bytes / time
21,803,008 / 8,880
03:16:58 INFO - TinderboxPrint: I/O write bytes / time
351,571,968 / 341,164
03:16:58 INFO - TinderboxPrint: CPU idle
2,857.2 (56.4%)
03:16:58 INFO - TinderboxPrint: CPU system
659.0 (13.0%)
03:16:58 INFO - TinderboxPrint: CPU user
1,523.9 (30.1%)
03:16:58 INFO - TinderboxPrint: Swap in / out
0 / 0
03:16:58 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5783552; Read time: 0; Write time: 5512
03:16:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:16:58 INFO - run-tests - Wall time: 655s; CPU: 44.0%; Read bytes: 21786624; Write bytes: 345788416; Read time: 8880; Write time: 335652
03:16:59 INFO - Running post-run listener: _upload_blobber_files
03:16:59 INFO - Blob upload gear active.
03:16:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:16:59 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
03:16:59 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']
03:16:59 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
03:16:59 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
03:16:59 INFO - SNIMissingWarning
03:16:59 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
03:16:59 INFO - InsecurePlatformWarning
03:16:59 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.)
03:16:59 INFO - SubjectAltNameWarning
03:16:59 INFO - (blobuploader) - INFO - Open directory for files ...
03:16:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:16:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:16:59 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:16:59 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
03:16:59 INFO - InsecurePlatformWarning
03:16:59 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.)
03:16:59 INFO - SubjectAltNameWarning
03:17:00 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
03:17:00 INFO - InsecurePlatformWarning
03:17:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:17:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:17:00 INFO - (blobuploader) - INFO - Done attempting.
03:17:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:17:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:17:00 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:17:00 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
03:17:00 INFO - InsecurePlatformWarning
03:17:00 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.)
03:17:00 INFO - SubjectAltNameWarning
03:17:01 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
03:17:01 INFO - InsecurePlatformWarning
03:17:01 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:17:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:17:01 INFO - (blobuploader) - INFO - Done attempting.
03:17:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:17:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:17:01 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:17:01 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
03:17:01 INFO - InsecurePlatformWarning
03:17:01 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.)
03:17:01 INFO - SubjectAltNameWarning
03:17:03 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
03:17:03 INFO - InsecurePlatformWarning
03:17:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:17:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:17:04 INFO - (blobuploader) - INFO - Done attempting.
03:17:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:17:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:17:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:17:04 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
03:17:04 INFO - InsecurePlatformWarning
03:17:04 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.)
03:17:04 INFO - SubjectAltNameWarning
03:17:04 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
03:17:04 INFO - InsecurePlatformWarning
03:17:05 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:17:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:17:05 INFO - (blobuploader) - INFO - Done attempting.
03:17:05 INFO - (blobuploader) - INFO - Iteration through files over.
03:17:05 INFO - Return code: 0
03:17:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:17:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:17:05 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b3a8f8544d16546a5b40d934e2a856f4792c360faab863fd78ed0ff2e24c3f24b197cce042a2f967dde736f2c1c6b6297ee1423474cf42ddfe71aa6e4cced82", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c907af876c72565e04318289297b5793b38b872f9f5803293fadf6502f439ecdbc8c1a65e0ce3bcaa399dc10e7c2c048902d16fbbda64fc49392c7fac747a98", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/974c6d1084be5b0558241152a3a1a0c6bb3691c60f8546bd042d59854e8a37af2f8ec9cfd0272c4584b93d2ada443687d597fc0359b247966a8fb4777c015564", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b61d505c5e533301d3d4227a44c893310a1d75146012291e1e463b8aa14eae13aed6cd78fae849c2d2e3135d9ff0fd66dd074a99477b0a0a65e1dda58afb18e8"}
03:17:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:17:05 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:17:05 INFO - Contents:
03:17:05 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b3a8f8544d16546a5b40d934e2a856f4792c360faab863fd78ed0ff2e24c3f24b197cce042a2f967dde736f2c1c6b6297ee1423474cf42ddfe71aa6e4cced82", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c907af876c72565e04318289297b5793b38b872f9f5803293fadf6502f439ecdbc8c1a65e0ce3bcaa399dc10e7c2c048902d16fbbda64fc49392c7fac747a98", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/974c6d1084be5b0558241152a3a1a0c6bb3691c60f8546bd042d59854e8a37af2f8ec9cfd0272c4584b93d2ada443687d597fc0359b247966a8fb4777c015564", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b61d505c5e533301d3d4227a44c893310a1d75146012291e1e463b8aa14eae13aed6cd78fae849c2d2e3135d9ff0fd66dd074a99477b0a0a65e1dda58afb18e8"}
03:17:05 INFO - Running post-run listener: copy_logs_to_upload_dir
03:17:05 INFO - Copying logs to upload dir...
03:17:05 INFO - mkdir: /builds/slave/test/build/upload/logs
03:17:05 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=737.303317
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 17 secs) (at 2017-11-25 03:17:05.159701) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-25 03:17:05.162861) =========
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=a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855
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/0b3a8f8544d16546a5b40d934e2a856f4792c360faab863fd78ed0ff2e24c3f24b197cce042a2f967dde736f2c1c6b6297ee1423474cf42ddfe71aa6e4cced82", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c907af876c72565e04318289297b5793b38b872f9f5803293fadf6502f439ecdbc8c1a65e0ce3bcaa399dc10e7c2c048902d16fbbda64fc49392c7fac747a98", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/974c6d1084be5b0558241152a3a1a0c6bb3691c60f8546bd042d59854e8a37af2f8ec9cfd0272c4584b93d2ada443687d597fc0359b247966a8fb4777c015564", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b61d505c5e533301d3d4227a44c893310a1d75146012291e1e463b8aa14eae13aed6cd78fae849c2d2e3135d9ff0fd66dd074a99477b0a0a65e1dda58afb18e8"}
build_url:https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011274
build_url: 'https://queue.taskcluster.net/v1/task/WqwZibDQSLKtqYt5e9GqOQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0b3a8f8544d16546a5b40d934e2a856f4792c360faab863fd78ed0ff2e24c3f24b197cce042a2f967dde736f2c1c6b6297ee1423474cf42ddfe71aa6e4cced82", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c907af876c72565e04318289297b5793b38b872f9f5803293fadf6502f439ecdbc8c1a65e0ce3bcaa399dc10e7c2c048902d16fbbda64fc49392c7fac747a98", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/974c6d1084be5b0558241152a3a1a0c6bb3691c60f8546bd042d59854e8a37af2f8ec9cfd0272c4584b93d2ada443687d597fc0359b247966a8fb4777c015564", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b61d505c5e533301d3d4227a44c893310a1d75146012291e1e463b8aa14eae13aed6cd78fae849c2d2e3135d9ff0fd66dd074a99477b0a0a65e1dda58afb18e8"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-25 03:17:05.193000) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 03:17:05.193541) =========
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=a0d2d2de893abaa8702a9954000001a7-1511598973.738796-373985855
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004810
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 03:17:05.274582) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-25 03:17:05.274900) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-25 03:17:05.275252) =========
========= Total master_lag: 0.21 =========