builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-037
starttime: 1510847325.64
results: success (0)
revision: d49e40cb14e5de521fdade4a872d0fa804d7d400
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.636247) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.636768) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.637050) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.728846) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.729133) =========
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=56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-16 07:48: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% 37.4M=0s
2017-11-16 07:48:45 (37.4 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.118532
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.887164) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.887494) =========
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=56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020836
========= master_lag: 0.05 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:45.954634) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-16 07:48:45.954968) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d49e40cb14e5de521fdade4a872d0fa804d7d400 --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 d49e40cb14e5de521fdade4a872d0fa804d7d400 --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=56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-16 07:48:46,010 truncating revision to first 12 chars
2017-11-16 07:48:46,010 Setting DEBUG logging.
2017-11-16 07:48:46,010 attempt 1/10
2017-11-16 07:48:46,010 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d49e40cb14e5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-16 07:48:46,359 unpacking tar archive at: mozilla-central-d49e40cb14e5/testing/mozharness/
program finished with exit code 0
elapsedTime=1.007515
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-16 07:48:46.982799) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:46.983115) =========
script_repo_revision: d49e40cb14e5de521fdade4a872d0fa804d7d400
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:46.983535) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:46.983809) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-16 07:48:47.023305) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 13 mins, 15 secs) (at 2017-11-16 07:48:47.023708) =========
/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=56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
07:48:47 INFO - MultiFileLogger online at 20171116 07:48:47 in /builds/slave/test
07:48:47 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
07:48:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
07:48:47 INFO - {'append_to_log': False,
07:48:47 INFO - 'base_work_dir': '/builds/slave/test',
07:48:47 INFO - 'blob_upload_branch': 'Firefox',
07:48:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
07:48:47 INFO - 'branch': 'Firefox',
07:48:47 INFO - 'buildbot_json_path': 'buildprops.json',
07:48:47 INFO - 'code_coverage': False,
07:48:47 INFO - 'config_files': ('talos/linux_config.py',),
07:48:47 INFO - 'default_actions': ('clobber',
07:48:47 INFO - 'read-buildbot-config',
07:48:47 INFO - 'download-and-extract',
07:48:47 INFO - 'populate-webroot',
07:48:47 INFO - 'create-virtualenv',
07:48:47 INFO - 'install',
07:48:47 INFO - 'setup-mitmproxy',
07:48:47 INFO - 'run-tests'),
07:48:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
07:48:47 INFO - 'disable_ccov_upload': False,
07:48:47 INFO - 'disable_stylo': False,
07:48:47 INFO - 'download_minidump_stackwalk': True,
07:48:47 INFO - 'download_symbols': 'ondemand',
07:48:47 INFO - 'e10s': False,
07:48:47 INFO - 'enable_stylo': False,
07:48:47 INFO - 'enable_webrender': False,
07:48:47 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
07:48:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
07:48:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
07:48:47 INFO - 'gecko_profile': False,
07:48:47 INFO - 'gecko_profile_interval': 0,
07:48:47 INFO - 'installer_path': 'installer.exe',
07:48:47 INFO - 'jsd_code_coverage': False,
07:48:47 INFO - 'log_level': 'info',
07:48:47 INFO - 'log_name': 'talos',
07:48:47 INFO - 'log_to_console': True,
07:48:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
07:48:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
07:48:47 INFO - 'opt_config_files': (),
07:48:47 INFO - 'pip_index': False,
07:48:47 INFO - 'suite': 'g4-e10s',
07:48:47 INFO - 'system_bits': '32',
07:48:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
07:48:47 INFO - 'title': 'talos-linux64-ix-037',
07:48:47 INFO - 'tooltool_cache': '/builds/tooltool_cache',
07:48:47 INFO - 'use_talos_json': True,
07:48:47 INFO - 'verify': 'False',
07:48:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
07:48:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
07:48:47 INFO - 'work_dir': 'build'}
07:48:47 INFO - [mozharness: 2017-11-16 15:48:47.220032Z] Running clobber step.
07:48:47 INFO - Running pre-action listener: _resource_record_pre_action
07:48:47 INFO - Running main action method: clobber
07:48:47 INFO - rmtree: /builds/slave/test/build
07:48:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
07:48:48 INFO - Running post-action listener: _resource_record_post_action
07:48:48 INFO - [mozharness: 2017-11-16 15:48:48.794840Z] Finished clobber step (success)
07:48:48 INFO - [mozharness: 2017-11-16 15:48:48.794935Z] Running read-buildbot-config step.
07:48:48 INFO - Running pre-action listener: _resource_record_pre_action
07:48:48 INFO - Running main action method: read_buildbot_config
07:48:48 INFO - Using buildbot properties:
07:48:48 INFO - {
07:48:48 INFO - "project": "",
07:48:48 INFO - "product": "firefox",
07:48:48 INFO - "who": "nfroyd@mozilla.com",
07:48:48 INFO - "installer_path": "public/build/target.tar.bz2",
07:48:48 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
07:48:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
07:48:48 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
07:48:48 INFO - "stage_platform": "linux64-qr",
07:48:48 INFO - "basedir": "/builds/slave/test",
07:48:48 INFO - "buildnumber": 201,
07:48:48 INFO - "platform": "ubuntu64_hw_qr",
07:48:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
07:48:48 INFO - "slavebuilddir": "test",
07:48:48 INFO - "taskId": "VSy-Fht_Tw-TsxnkxpSjQw",
07:48:48 INFO - "branch": "mozilla-central",
07:48:48 INFO - "script_repo_revision": "production",
07:48:48 INFO - "revision": "d49e40cb14e5de521fdade4a872d0fa804d7d400",
07:48:48 INFO - "slavename": "talos-linux64-ix-037",
07:48:48 INFO - "repo_path": "mozilla-central"
07:48:48 INFO - }
07:48:48 INFO - Finding installer, test and symbols from parent task.
07:48:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VSy-Fht_Tw-TsxnkxpSjQw'}, attempt #1
07:48:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VSy-Fht_Tw-TsxnkxpSjQw'}, attempt #1
07:48:49 INFO - Task dependencies: MrN9yGWBREWQ-0lCfud7HA
07:48:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA'}, attempt #1
07:48:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2
07:48:50 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.test_packages.json
07:48:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.crashreporter-symbols.zip
07:48:50 INFO - Running post-action listener: _resource_record_post_action
07:48:50 INFO - [mozharness: 2017-11-16 15:48:50.145523Z] Finished read-buildbot-config step (success)
07:48:50 INFO - [mozharness: 2017-11-16 15:48:50.145737Z] Running download-and-extract step.
07:48:50 INFO - Running pre-action listener: _resource_record_pre_action
07:48:50 INFO - Running main action method: download_and_extract
07:48:50 INFO - mkdir: /builds/slave/test/build/tests
07:48:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:48:50 INFO - trying https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.test_packages.json
07:48:50 INFO - Downloading https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
07:48:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
07:48:50 INFO - Downloaded 989 bytes.
07:48:50 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
07:48:50 INFO - Using the following test package requirements:
07:48:50 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
07:48:50 INFO - u'common': [u'target.common.tests.zip'],
07:48:50 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
07:48:50 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
07:48:50 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
07:48:50 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
07:48:50 INFO - u'mozbase': [u'target.common.tests.zip'],
07:48:50 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
07:48:50 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
07:48:50 INFO - u'web-platform': [u'target.common.tests.zip',
07:48:50 INFO - u'target.web-platform.tests.tar.gz'],
07:48:50 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
07:48:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
07:48:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.common.tests.zip
07:48:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.common.tests.zip'}, attempt #1
07:48:50 INFO - Fetch https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.common.tests.zip into memory
07:48:53 INFO - Content-Length response header: 59050019
07:48:53 INFO - Bytes received: 59050019
07:49:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.talos.tests.zip
07:49:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
07:49:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.talos.tests.zip into memory
07:49:05 INFO - Content-Length response header: 18771831
07:49:05 INFO - Bytes received: 18771831
07:49: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')]}
07:49:06 INFO - trying https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2
07:49:06 INFO - Downloading https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
07:49:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
07:49:09 INFO - Downloaded 62563037 bytes.
07:49:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2
07:49:09 INFO - mkdir: /builds/slave/test/properties
07:49:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
07:49:09 INFO - Writing to file /builds/slave/test/properties/build_url
07:49:09 INFO - Contents:
07:49:09 INFO - build_url:https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2
07:49:09 INFO - Running post-action listener: _resource_record_post_action
07:49:09 INFO - Running post-action listener: find_tests_for_verification
07:49:09 INFO - Running post-action listener: set_extra_try_arguments
07:49:09 INFO - [mozharness: 2017-11-16 15:49:09.417258Z] Finished download-and-extract step (success)
07:49:09 INFO - [mozharness: 2017-11-16 15:49:09.417477Z] Running populate-webroot step.
07:49:09 INFO - Running pre-action listener: _resource_record_pre_action
07:49:09 INFO - Running main action method: populate_webroot
07:49:09 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
07:49:09 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['tresize',
07:49:09 INFO - 'tcanvasmark']},
07:49:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
07:49:09 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['dromaeo_css',
07:49:09 INFO - 'kraken']},
07:49:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
07:49:09 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
07:49:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'tests': ['damp', 'tps']},
07:49:09 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['damp', 'tps']},
07:49:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
07:49:09 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['dromaeo_dom']},
07:49:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
07:49:09 INFO - 'glvideo',
07:49:09 INFO - 'displaylist_mutate']},
07:49:09 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['basic_compositor_video',
07:49:09 INFO - 'glvideo']},
07:49:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
07:49:09 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['ts_paint_webext',
07:49:09 INFO - 'tp5o_webext']},
07:49:09 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
07:49:09 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:49:09 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:49:09 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:49:09 INFO - 'talos_options': ['--mitmproxy',
07:49:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:49:09 INFO - '--firstNonBlankPaint'],
07:49:09 INFO - 'tests': ['tp6_google_heavy',
07:49:09 INFO - 'tp6_youtube_heavy',
07:49:09 INFO - 'tp6_amazon_heavy',
07:49:09 INFO - 'tp6_facebook_heavy']},
07:49:09 INFO - 'other-e10s': {'tests': ['a11yr',
07:49:09 INFO - 'ts_paint',
07:49:09 INFO - 'tpaint',
07:49:09 INFO - 'sessionrestore',
07:49:09 INFO - 'sessionrestore_many_windows',
07:49:09 INFO - 'sessionrestore_no_auto_restore',
07:49:09 INFO - 'tabpaint',
07:49:09 INFO - 'cpstartup']},
07:49:09 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['a11yr',
07:49:09 INFO - 'ts_paint',
07:49:09 INFO - 'tpaint',
07:49:09 INFO - 'sessionrestore',
07:49:09 INFO - 'sessionrestore_many_windows',
07:49:09 INFO - 'sessionrestore_no_auto_restore',
07:49:09 INFO - 'tabpaint',
07:49:09 INFO - 'cpstartup']},
07:49:09 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
07:49:09 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
07:49:09 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['perf_reftest_singletons']},
07:49:09 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['perf_reftest']},
07:49:09 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
07:49:09 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['speedometer']},
07:49:09 INFO - 'svgr-e10s': {'tests': ['tsvgx',
07:49:09 INFO - 'tsvgr_opacity',
07:49:09 INFO - 'tart',
07:49:09 INFO - 'tscrollx',
07:49:09 INFO - 'tsvg_static']},
07:49:09 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['tsvgx',
07:49:09 INFO - 'tsvgr_opacity',
07:49:09 INFO - 'tart',
07:49:09 INFO - 'tscrollx',
07:49:09 INFO - 'tsvg_static']},
07:49:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
07:49:09 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'talos_options': ['--disable-stylo'],
07:49:09 INFO - 'tests': ['tp5o']},
07:49:09 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:49:09 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:49:09 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:49:09 INFO - 'talos_options': ['--mitmproxy',
07:49:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:49:09 INFO - '--firstNonBlankPaint'],
07:49:09 INFO - 'tests': ['tp6_google',
07:49:09 INFO - 'tp6_youtube',
07:49:09 INFO - 'tp6_amazon',
07:49:09 INFO - 'tp6_facebook']},
07:49:09 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:49:09 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:49:09 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:49:09 INFO - 'talos_options': ['--disable-stylo',
07:49:09 INFO - '--mitmproxy',
07:49:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:49:09 INFO - '--firstNonBlankPaint'],
07:49:09 INFO - 'tests': ['tp6_google',
07:49:09 INFO - 'tp6_youtube',
07:49:09 INFO - 'tp6_amazon',
07:49:09 INFO - 'tp6_facebook']},
07:49:09 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
07:49:09 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
07:49:09 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
07:49:09 INFO - 'talos_options': ['--stylo-threads=1',
07:49:09 INFO - '--mitmproxy',
07:49:09 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
07:49:09 INFO - '--firstNonBlankPaint'],
07:49:09 INFO - 'tests': ['tp6_google',
07:49:09 INFO - 'tp6_youtube',
07:49:09 INFO - 'tp6_amazon',
07:49:09 INFO - 'tp6_facebook']},
07:49:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'talos_options': ['--xperf_path',
07:49:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:49:09 INFO - 'tests': ['tp5n']},
07:49:09 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
07:49:09 INFO - 'talos_options': ['--disable-stylo',
07:49:09 INFO - '--xperf_path',
07:49:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
07:49:09 INFO - 'tests': ['tp5n']}}}
07:49:09 INFO - Running post-action listener: _resource_record_post_action
07:49:09 INFO - [mozharness: 2017-11-16 15:49:09.439644Z] Finished populate-webroot step (success)
07:49:09 INFO - [mozharness: 2017-11-16 15:49:09.439757Z] Running create-virtualenv step.
07:49:09 INFO - Running pre-action listener: _resource_record_pre_action
07:49:09 INFO - Running main action method: create_virtualenv
07:49:09 INFO - Creating virtualenv /builds/slave/test/build/venv
07:49:09 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
07:49:09 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
07:49:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
07:49:09 INFO - Using real prefix '/usr'
07:49:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python
07:49:09 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
07:49:11 INFO - Installing setuptools, pip, wheel...done.
07:49:11 INFO - Return code: 0
07:49:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:11 INFO - Using env: {'DISPLAY': ':0',
07:49:11 INFO - 'HOME': '/home/cltbld',
07:49:11 INFO - 'LANG': 'en_US.UTF-8',
07:49:11 INFO - 'LANGUAGE': 'en_US:en',
07:49:11 INFO - 'LOGNAME': 'cltbld',
07:49:11 INFO - 'MAIL': '/var/mail/cltbld',
07:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:11 INFO - 'MOZ_NO_REMOTE': '1',
07:49:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:11 INFO - 'NO_EM_RESTART': '1',
07:49:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:11 INFO - 'PWD': '/builds/slave/test',
07:49:11 INFO - 'SHELL': '/bin/bash',
07:49:11 INFO - 'SHLVL': '1',
07:49:11 INFO - 'TERM': 'linux',
07:49:11 INFO - 'TMOUT': '86400',
07:49:11 INFO - 'USER': 'cltbld',
07:49:11 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:11 INFO - '_': '/tools/buildbot/bin/python'}
07:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
07:49:12 INFO - Return code: 0
07:49:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:12 INFO - Using env: {'DISPLAY': ':0',
07:49:12 INFO - 'HOME': '/home/cltbld',
07:49:12 INFO - 'LANG': 'en_US.UTF-8',
07:49:12 INFO - 'LANGUAGE': 'en_US:en',
07:49:12 INFO - 'LOGNAME': 'cltbld',
07:49:12 INFO - 'MAIL': '/var/mail/cltbld',
07:49:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:12 INFO - 'MOZ_NO_REMOTE': '1',
07:49:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:12 INFO - 'NO_EM_RESTART': '1',
07:49:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:12 INFO - 'PWD': '/builds/slave/test',
07:49:12 INFO - 'SHELL': '/bin/bash',
07:49:12 INFO - 'SHLVL': '1',
07:49:12 INFO - 'TERM': 'linux',
07:49:12 INFO - 'TMOUT': '86400',
07:49:12 INFO - 'USER': 'cltbld',
07:49:12 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:12 INFO - '_': '/tools/buildbot/bin/python'}
07:49:12 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:12 INFO - Collecting psutil>=3.1.1
07:49:13 INFO - Installing collected packages: psutil
07:49:13 INFO - Successfully installed psutil-3.1.1
07:49:13 INFO - Return code: 0
07:49:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:13 INFO - Using env: {'DISPLAY': ':0',
07:49:13 INFO - 'HOME': '/home/cltbld',
07:49:13 INFO - 'LANG': 'en_US.UTF-8',
07:49:13 INFO - 'LANGUAGE': 'en_US:en',
07:49:13 INFO - 'LOGNAME': 'cltbld',
07:49:13 INFO - 'MAIL': '/var/mail/cltbld',
07:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:13 INFO - 'MOZ_NO_REMOTE': '1',
07:49:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:13 INFO - 'NO_EM_RESTART': '1',
07:49:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:13 INFO - 'PWD': '/builds/slave/test',
07:49:13 INFO - 'SHELL': '/bin/bash',
07:49:13 INFO - 'SHLVL': '1',
07:49:13 INFO - 'TERM': 'linux',
07:49:13 INFO - 'TMOUT': '86400',
07:49:13 INFO - 'USER': 'cltbld',
07:49:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:13 INFO - '_': '/tools/buildbot/bin/python'}
07:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:14 INFO - Collecting mozsystemmonitor==0.3
07:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
07:49:15 INFO - Installing collected packages: mozsystemmonitor
07:49:15 INFO - Successfully installed mozsystemmonitor-0.3
07:49:15 INFO - Return code: 0
07:49:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:15 INFO - Using env: {'DISPLAY': ':0',
07:49:15 INFO - 'HOME': '/home/cltbld',
07:49:15 INFO - 'LANG': 'en_US.UTF-8',
07:49:15 INFO - 'LANGUAGE': 'en_US:en',
07:49:15 INFO - 'LOGNAME': 'cltbld',
07:49:15 INFO - 'MAIL': '/var/mail/cltbld',
07:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:15 INFO - 'MOZ_NO_REMOTE': '1',
07:49:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:15 INFO - 'NO_EM_RESTART': '1',
07:49:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:15 INFO - 'PWD': '/builds/slave/test',
07:49:15 INFO - 'SHELL': '/bin/bash',
07:49:15 INFO - 'SHLVL': '1',
07:49:15 INFO - 'TERM': 'linux',
07:49:15 INFO - 'TMOUT': '86400',
07:49:15 INFO - 'USER': 'cltbld',
07:49:15 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:15 INFO - '_': '/tools/buildbot/bin/python'}
07:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:16 INFO - Collecting jsonschema==2.5.1
07:49:17 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
07:49:17 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
07:49:18 INFO - Installing collected packages: functools32, jsonschema
07:49:18 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
07:49:18 INFO - Return code: 0
07:49:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:18 INFO - Using env: {'DISPLAY': ':0',
07:49:18 INFO - 'HOME': '/home/cltbld',
07:49:18 INFO - 'LANG': 'en_US.UTF-8',
07:49:18 INFO - 'LANGUAGE': 'en_US:en',
07:49:18 INFO - 'LOGNAME': 'cltbld',
07:49:18 INFO - 'MAIL': '/var/mail/cltbld',
07:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:18 INFO - 'MOZ_NO_REMOTE': '1',
07:49:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:18 INFO - 'NO_EM_RESTART': '1',
07:49:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:18 INFO - 'PWD': '/builds/slave/test',
07:49:18 INFO - 'SHELL': '/bin/bash',
07:49:18 INFO - 'SHLVL': '1',
07:49:18 INFO - 'TERM': 'linux',
07:49:18 INFO - 'TMOUT': '86400',
07:49:18 INFO - 'USER': 'cltbld',
07:49:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:18 INFO - '_': '/tools/buildbot/bin/python'}
07:49:18 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
07:49:18 INFO - Return code: 0
07:49:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:18 INFO - Using env: {'DISPLAY': ':0',
07:49:18 INFO - 'HOME': '/home/cltbld',
07:49:18 INFO - 'LANG': 'en_US.UTF-8',
07:49:18 INFO - 'LANGUAGE': 'en_US:en',
07:49:18 INFO - 'LOGNAME': 'cltbld',
07:49:18 INFO - 'MAIL': '/var/mail/cltbld',
07:49:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:18 INFO - 'MOZ_NO_REMOTE': '1',
07:49:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:18 INFO - 'NO_EM_RESTART': '1',
07:49:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:18 INFO - 'PWD': '/builds/slave/test',
07:49:18 INFO - 'SHELL': '/bin/bash',
07:49:18 INFO - 'SHLVL': '1',
07:49:18 INFO - 'TERM': 'linux',
07:49:18 INFO - 'TMOUT': '86400',
07:49:18 INFO - 'USER': 'cltbld',
07:49:18 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:18 INFO - '_': '/tools/buildbot/bin/python'}
07:49:19 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:19 INFO - Collecting blobuploader==1.2.4
07:49:20 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
07:49:21 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
07:49:22 INFO - Installing collected packages: requests, docopt, blobuploader
07:49:22 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
07:49:22 INFO - Return code: 0
07:49:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:22 INFO - Using env: {'DISPLAY': ':0',
07:49:22 INFO - 'HOME': '/home/cltbld',
07:49:22 INFO - 'LANG': 'en_US.UTF-8',
07:49:22 INFO - 'LANGUAGE': 'en_US:en',
07:49:22 INFO - 'LOGNAME': 'cltbld',
07:49:22 INFO - 'MAIL': '/var/mail/cltbld',
07:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:22 INFO - 'MOZ_NO_REMOTE': '1',
07:49:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:22 INFO - 'NO_EM_RESTART': '1',
07:49:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:22 INFO - 'PWD': '/builds/slave/test',
07:49:22 INFO - 'SHELL': '/bin/bash',
07:49:22 INFO - 'SHLVL': '1',
07:49:22 INFO - 'TERM': 'linux',
07:49:22 INFO - 'TMOUT': '86400',
07:49:22 INFO - 'USER': 'cltbld',
07:49:22 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:22 INFO - '_': '/tools/buildbot/bin/python'}
07:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:23 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
07:49:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
07:49:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
07:49:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
07:49:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
07:49:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
07:49:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
07:49:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
07:49:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
07:49:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
07:49:25 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
07:49:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
07:49:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
07:49:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
07:49:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
07:49:26 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
07:49:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
07:49:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
07:49:26 INFO - Running setup.py install for manifestparser: started
07:49:26 INFO - Running setup.py install for manifestparser: finished with status 'done'
07:49:26 INFO - Running setup.py install for mozcrash: started
07:49:26 INFO - Running setup.py install for mozcrash: finished with status 'done'
07:49:26 INFO - Running setup.py install for mozdebug: started
07:49:27 INFO - Running setup.py install for mozdebug: finished with status 'done'
07:49:27 INFO - Running setup.py install for mozdevice: started
07:49:27 INFO - Running setup.py install for mozdevice: finished with status 'done'
07:49:27 INFO - Running setup.py install for mozfile: started
07:49:27 INFO - Running setup.py install for mozfile: finished with status 'done'
07:49:27 INFO - Running setup.py install for mozhttpd: started
07:49:27 INFO - Running setup.py install for mozhttpd: finished with status 'done'
07:49:27 INFO - Running setup.py install for mozinfo: started
07:49:27 INFO - Running setup.py install for mozinfo: finished with status 'done'
07:49:27 INFO - Running setup.py install for mozInstall: started
07:49:28 INFO - Running setup.py install for mozInstall: finished with status 'done'
07:49:28 INFO - Running setup.py install for mozleak: started
07:49:28 INFO - Running setup.py install for mozleak: finished with status 'done'
07:49:28 INFO - Running setup.py install for mozlog: started
07:49:28 INFO - Running setup.py install for mozlog: finished with status 'done'
07:49:28 INFO - Running setup.py install for moznetwork: started
07:49:28 INFO - Running setup.py install for moznetwork: finished with status 'done'
07:49:28 INFO - Running setup.py install for mozprocess: started
07:49:29 INFO - Running setup.py install for mozprocess: finished with status 'done'
07:49:29 INFO - Running setup.py install for mozprofile: started
07:49:29 INFO - Running setup.py install for mozprofile: finished with status 'done'
07:49:29 INFO - Running setup.py install for mozrunner: started
07:49:29 INFO - Running setup.py install for mozrunner: finished with status 'done'
07:49:29 INFO - Running setup.py install for mozscreenshot: started
07:49:29 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
07:49:29 INFO - Running setup.py install for moztest: started
07:49:29 INFO - Running setup.py install for moztest: finished with status 'done'
07:49:30 INFO - Running setup.py install for mozversion: started
07:49:30 INFO - Running setup.py install for mozversion: finished with status 'done'
07:49: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
07:49:30 INFO - Return code: 0
07:49:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:30 INFO - Using env: {'DISPLAY': ':0',
07:49:30 INFO - 'HOME': '/home/cltbld',
07:49:30 INFO - 'LANG': 'en_US.UTF-8',
07:49:30 INFO - 'LANGUAGE': 'en_US:en',
07:49:30 INFO - 'LOGNAME': 'cltbld',
07:49:30 INFO - 'MAIL': '/var/mail/cltbld',
07:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:30 INFO - 'MOZ_NO_REMOTE': '1',
07:49:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:30 INFO - 'NO_EM_RESTART': '1',
07:49:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:30 INFO - 'PWD': '/builds/slave/test',
07:49:30 INFO - 'SHELL': '/bin/bash',
07:49:30 INFO - 'SHLVL': '1',
07:49:30 INFO - 'TERM': 'linux',
07:49:30 INFO - 'TMOUT': '86400',
07:49:30 INFO - 'USER': 'cltbld',
07:49:30 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:30 INFO - '_': '/tools/buildbot/bin/python'}
07:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:30 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
07:49:31 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))
07:49:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
07:49: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))
07:49:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
07:49: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))
07:49:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
07:49: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))
07:49:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
07:49:32 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))
07:49:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
07:49:32 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))
07:49:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
07:49: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))
07:49:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
07:49: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))
07:49:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
07:49: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))
07:49:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
07:49:33 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))
07:49:33 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
07:49:33 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))
07:49:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
07:49: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))
07:49:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
07:49: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))
07:49:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
07:49: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))
07:49:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
07:49:34 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))
07:49:34 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
07:49:34 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))
07:49:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
07:49: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))
07:49:34 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
07:49:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49:35 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
07:49:36 INFO - Installing collected packages: six, blessings
07:49:36 INFO - Successfully installed blessings-1.6 six-1.10.0
07:49:36 INFO - Return code: 0
07:49:36 INFO - Done creating virtualenv /builds/slave/test/build/venv.
07:49:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
07:49:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
07:49:37 INFO - Reading from file tmpfile_stdout
07:49:37 INFO - Reading from file tmpfile_stderr
07:49:37 INFO - Current package versions:
07:49:37 INFO - blessings == 1.6
07:49:37 INFO - blobuploader == 1.2.4
07:49:37 INFO - docopt == 0.6.1
07:49:37 INFO - functools32 == 3.2.3.post2
07:49:37 INFO - jsonschema == 2.5.1
07:49:37 INFO - manifestparser == 1.2
07:49:37 INFO - mozInstall == 1.14
07:49:37 INFO - mozcrash == 1.0
07:49:37 INFO - mozdebug == 0.1
07:49:37 INFO - mozdevice == 0.51
07:49:37 INFO - mozfile == 1.2
07:49:37 INFO - mozhttpd == 0.7
07:49:37 INFO - mozinfo == 0.10
07:49:37 INFO - mozleak == 0.1
07:49:37 INFO - mozlog == 3.5
07:49:37 INFO - moznetwork == 0.27
07:49:37 INFO - mozprocess == 0.25
07:49:37 INFO - mozprofile == 0.29
07:49:37 INFO - mozrunner == 6.13
07:49:37 INFO - mozscreenshot == 0.1
07:49:37 INFO - mozsystemmonitor == 0.3
07:49:37 INFO - moztest == 0.8
07:49:37 INFO - mozversion == 1.4
07:49:37 INFO - psutil == 3.1.1
07:49:37 INFO - requests == 1.2.3
07:49:37 INFO - six == 1.10.0
07:49:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv
07:49: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')]}
07:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49: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')]}
07:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49: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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49: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
07:49: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
07:49:37 INFO - Using env: {'DISPLAY': ':0',
07:49:37 INFO - 'HOME': '/home/cltbld',
07:49:37 INFO - 'LANG': 'en_US.UTF-8',
07:49:37 INFO - 'LANGUAGE': 'en_US:en',
07:49:37 INFO - 'LOGNAME': 'cltbld',
07:49:37 INFO - 'MAIL': '/var/mail/cltbld',
07:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:37 INFO - 'MOZ_NO_REMOTE': '1',
07:49:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:37 INFO - 'NO_EM_RESTART': '1',
07:49:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:37 INFO - 'PWD': '/builds/slave/test',
07:49:37 INFO - 'SHELL': '/bin/bash',
07:49:37 INFO - 'SHLVL': '1',
07:49:37 INFO - 'TERM': 'linux',
07:49:37 INFO - 'TMOUT': '86400',
07:49:37 INFO - 'USER': 'cltbld',
07:49:37 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:37 INFO - '_': '/tools/buildbot/bin/python'}
07:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49:37 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
07:49:38 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
07:49:39 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
07:49: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))
07:49: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))
07:49: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))
07:49: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))
07:49:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
07:49: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))
07:49:42 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
07:49:42 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
07:49:43 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
07:49:43 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
07:49:44 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
07:49:44 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
07:49:44 INFO - Found existing installation: requests 1.2.3
07:49:44 INFO - Uninstalling requests-1.2.3:
07:49:44 INFO - Successfully uninstalled requests-1.2.3
07:49: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
07:49:45 INFO - Return code: 0
07:49:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
07:49:45 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')]}
07:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
07:49:45 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')]}
07:49:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
07:49:45 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 0x2695d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2674a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28161a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319', '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
07:49:45 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
07:49:45 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
07:49:45 INFO - Using env: {'DISPLAY': ':0',
07:49:45 INFO - 'HOME': '/home/cltbld',
07:49:45 INFO - 'LANG': 'en_US.UTF-8',
07:49:45 INFO - 'LANGUAGE': 'en_US:en',
07:49:45 INFO - 'LOGNAME': 'cltbld',
07:49:45 INFO - 'MAIL': '/var/mail/cltbld',
07:49:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:49:45 INFO - 'MOZ_NO_REMOTE': '1',
07:49:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:49:45 INFO - 'NO_EM_RESTART': '1',
07:49:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:49:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:49:45 INFO - 'PWD': '/builds/slave/test',
07:49:45 INFO - 'SHELL': '/bin/bash',
07:49:45 INFO - 'SHLVL': '1',
07:49:45 INFO - 'TERM': 'linux',
07:49:45 INFO - 'TMOUT': '86400',
07:49:45 INFO - 'USER': 'cltbld',
07:49:45 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:49:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:49:45 INFO - '_': '/tools/buildbot/bin/python'}
07:49:45 INFO - Ignoring indexes: https://pypi.python.org/simple
07:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
07:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
07:49:45 INFO - Return code: 0
07:49:45 INFO - Running post-action listener: _resource_record_post_action
07:49:45 INFO - Running post-action listener: _start_resource_monitoring
07:49:45 INFO - Starting resource monitoring.
07:49:45 INFO - [mozharness: 2017-11-16 15:49:45.525531Z] Finished create-virtualenv step (success)
07:49:45 INFO - [mozharness: 2017-11-16 15:49:45.525982Z] Running install step.
07:49:45 INFO - Running pre-action listener: _resource_record_pre_action
07:49:45 INFO - Running main action method: install
07:49:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
07:49:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
07:49:45 INFO - Reading from file tmpfile_stdout
07:49:45 INFO - Reading from file tmpfile_stderr
07:49:46 INFO - Detecting whether we're running mozinstall >=1.0...
07:49:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
07:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
07:49:46 INFO - Reading from file tmpfile_stdout
07:49:46 INFO - Output received:
07:49:46 INFO - Usage: mozinstall [options] installer
07:49:46 INFO - Options:
07:49:46 INFO - -h, --help show this help message and exit
07:49:46 INFO - -d DEST, --destination=DEST
07:49:46 INFO - Directory to install application into. [default:
07:49:46 INFO - "/builds/slave/test"]
07:49:46 INFO - --app=APP Application being installed. [default: firefox]
07:49:46 INFO - mkdir: /builds/slave/test/build/application
07:49:46 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']
07:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
07:50:04 INFO - Reading from file tmpfile_stdout
07:50:04 INFO - Output received:
07:50:04 INFO - /builds/slave/test/build/application/firefox/firefox
07:50:04 INFO - Running post-action listener: _resource_record_post_action
07:50:04 INFO - [mozharness: 2017-11-16 15:50:04.499113Z] Finished install step (success)
07:50:04 INFO - [mozharness: 2017-11-16 15:50:04.499255Z] Running setup-mitmproxy step.
07:50:04 INFO - Running pre-action listener: _resource_record_pre_action
07:50:04 INFO - Running main action method: setup_mitmproxy
07:50:04 INFO - Skipping: mitmproxy is not required
07:50:04 INFO - Running post-action listener: _resource_record_post_action
07:50:04 INFO - [mozharness: 2017-11-16 15:50:04.499869Z] Finished setup-mitmproxy step (success)
07:50:04 INFO - [mozharness: 2017-11-16 15:50:04.500089Z] Running run-tests step.
07:50:04 INFO - Running pre-action listener: _resource_record_pre_action
07:50:04 INFO - Running pre-action listener: _set_gcov_prefix
07:50:04 INFO - Running main action method: run_tests
07:50:04 WARNING - Try message not found.
07:50:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
07:50:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
07:50:04 INFO - Python 2.7.3
07:50:04 INFO - Return code: 0
07:50:04 INFO - grabbing minidump binary from tooltool
07:50:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
07:50:04 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 0x2813320>, '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 0x2814220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28146d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
07:50:04 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
07:50:04 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
07:50:04 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
07:50:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
07:50:04 INFO - Return code: 0
07:50:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
07:50:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
07:50:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
07:50:04 INFO - ENV: RUST_BACKTRACE is now full
07:50:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
07:50:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
07:50: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/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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
07:50: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/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-037 --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
07:50:04 INFO - Using env: {'DISPLAY': ':0',
07:50:04 INFO - 'HOME': '/home/cltbld',
07:50:04 INFO - 'LANG': 'en_US.UTF-8',
07:50:04 INFO - 'LANGUAGE': 'en_US:en',
07:50:04 INFO - 'LOGNAME': 'cltbld',
07:50:04 INFO - 'MAIL': '/var/mail/cltbld',
07:50:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
07:50:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
07:50:04 INFO - 'MOZ_ACCELERATED': '1',
07:50:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:50:04 INFO - 'MOZ_NO_REMOTE': '1',
07:50:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
07:50:04 INFO - 'MOZ_WEBRENDER': '1',
07:50:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:50:04 INFO - 'NO_EM_RESTART': '1',
07:50:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:50:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:50:04 INFO - 'PWD': '/builds/slave/test',
07:50:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
07:50:04 INFO - 'RUST_BACKTRACE': 'full',
07:50:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
07:50:04 INFO - 'SHELL': '/bin/bash',
07:50:04 INFO - 'SHLVL': '1',
07:50:04 INFO - 'TERM': 'linux',
07:50:04 INFO - 'TMOUT': '86400',
07:50:04 INFO - 'USER': 'cltbld',
07:50:04 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:50:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:50:04 INFO - '_': '/tools/buildbot/bin/python'}
07:50: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/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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
07:50:04 INFO - mozversion application_buildid: 20171116145514
07:50:04 INFO - mozversion application_changeset: d49e40cb14e5de521fdade4a872d0fa804d7d400
07:50:04 INFO - mozversion application_display_name: Nightly
07:50:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
07:50:04 INFO - mozversion application_name: Firefox
07:50:04 INFO - mozversion application_remotingname: firefox
07:50:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
07:50:04 INFO - mozversion application_vendor: Mozilla
07:50:04 INFO - mozversion application_version: 59.0a1
07:50:04 INFO - mozversion platform_buildid: 20171116145514
07:50:04 INFO - mozversion platform_changeset: d49e40cb14e5de521fdade4a872d0fa804d7d400
07:50:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
07:50:04 INFO - mozversion platform_version: 59.0a1
07:50:04 INFO - using testdate: 1510847404
07:50:04 INFO - actual date: 1510847404
07:50:04 INFO - starting webserver on 'localhost:44760'
07:50:04 INFO - SUITE-START | Running 3 tests
07:50:04 INFO - TEST-START | basic_compositor_video
07:50:04 INFO - Initialising browser for basic_compositor_video test...
07:50:04 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
07:50:04 INFO - Installing Add-ons
07:50:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44760/getInfo.html -profile /tmp/tmpkfcDxp/profile
07:50:04 INFO - TEST-INFO | started process 21770 (/builds/slave/test/build/application/firefox/firefox http://localhost:44760/getInfo.html)
07:50:12 INFO - TEST-INFO | 21770: exit 0
07:50:13 INFO - Browser initialized.
07:50:13 INFO - Running cycle 1/1 for basic_compositor_video test...
07:50:13 INFO - Using env: {'DISPLAY': ':0',
07:50:13 INFO - 'HOME': '/home/cltbld',
07:50:13 INFO - 'JSGC_DISABLE_POISONING': '1',
07:50:13 INFO - 'LANG': 'en_US.UTF-8',
07:50:13 INFO - 'LANGUAGE': 'en_US:en',
07:50:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
07:50:13 INFO - 'LOGNAME': 'cltbld',
07:50:13 INFO - 'MAIL': '/var/mail/cltbld',
07:50:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
07:50:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
07:50:13 INFO - 'MOZ_ACCELERATED': '1',
07:50:13 INFO - 'MOZ_CRASHREPORTER': '1',
07:50:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:50:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
07:50:13 INFO - 'MOZ_NO_REMOTE': '1',
07:50:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
07:50:13 INFO - 'MOZ_WEBRENDER': '1',
07:50:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:50:13 INFO - 'NO_EM_RESTART': '1',
07:50:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:50:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:50:13 INFO - 'PWD': '/builds/slave/test',
07:50:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
07:50:13 INFO - 'RUST_BACKTRACE': 'full',
07:50:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
07:50:13 INFO - 'SHELL': '/bin/bash',
07:50:13 INFO - 'SHLVL': '1',
07:50:13 INFO - 'TERM': 'linux',
07:50:13 INFO - 'TMOUT': '86400',
07:50:13 INFO - 'USER': 'cltbld',
07:50:13 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:50:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:50:13 INFO - '_': '/tools/buildbot/bin/python'}
07:50:13 INFO - TEST-INFO | started process 22010 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkfcDxp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
07:50:13 INFO - PID 22010 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
07:50:14 INFO - PID 22010 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
07:50:14 INFO - PID 22010 |
07:50:14 INFO - PID 22010 | (/builds/slave/test/build/application/firefox/firefox:22073): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
07:50:14 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.9413333333333336 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4612792127921277 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5148533109807203 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4943079800498746 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0808008213552367 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.073548387096773 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 5.191098615916951 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.821566265060243 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.243804809052333 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.175323590814203 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.629537037037041 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.65253488372093 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.044448356807506 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.757567567567586 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.944456018518513 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.736195286195291 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.962749419953596 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.758327702702691 ms/frame
07:51:00 INFO - PID 22010 |
07:51:00 INFO - PID 22010 | Cycle 1(1): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:51:46 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4921677740863784 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4574938574938576 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5191015952980687 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5004250000000003 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0776461538461537 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0784153846153863 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.310890804597703 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.176283924843421 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.2502620396600586 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.1940775681341655 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.725834645669296 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.683817330210768 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.010280373831771 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.850719178082204 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.979151162790689 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.759898648648649 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.026276346604218 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.7344781144781125 ms/frame
07:51:46 INFO - PID 22010 |
07:51:46 INFO - PID 22010 | Cycle 1(2): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:51:48 INFO - PID 22010 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
07:52:32 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.496247920133112 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.46439039408867 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5065789473684212 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5035982478097623 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0771179487179494 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0628024502297095 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.166958333333334 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.159708939708939 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.219690576652603 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.1844769874477 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.739533965244866 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.663566433566438 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.042417840375594 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.780067796610154 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.976627906976738 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.781542372881357 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.96280742459397 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.804149659863939 ms/frame
07:52:32 INFO - PID 22010 |
07:52:32 INFO - PID 22010 | Cycle 1(3): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:53:18 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.487786069651741 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460965559655597 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5091889632107023 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.512801507537688 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0778153846153855 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0685122699386502 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.762809523809524 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 5.038967254408063 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.268001422475105 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.21198947368421 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.702617554858935 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.718643867924532 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.010572429906537 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.757432432432447 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010070093457938 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.804489795918381 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.930877598152424 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.7829152542372855 ms/frame
07:53:18 INFO - PID 22010 |
07:53:18 INFO - PID 22010 | Cycle 1(4): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:54:04 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.477803468208093 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4671208384710233 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.527982308340354 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.504399249061327 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0804722792607815 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0721351766513045 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.225267605633806 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.151358921161822 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.485306427503734 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.454265033407573 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.638276661514683 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.642250580046407 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.0100116822429985 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.78296610169491 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.960580046403717 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.737306397306384 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.977174418604646 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.828344709897617 ms/frame
07:54:04 INFO - PID 22010 |
07:54:04 INFO - PID 22010 | Cycle 1(5): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:54:50 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4839238410596027 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.463669950738917 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5239444911690496 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.507368421052633 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0776153846153838 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.073064516129033 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.601556748466258 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.526561085972846 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.581541984732824 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.537040816326528 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.680366614664589 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673714953271024 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.979046511627911 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.758040540540532 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.961218097447804 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.804574829931967 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.97682558139534 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.803027210884367 ms/frame
07:54:50 INFO - PID 22010 |
07:54:50 INFO - PID 22010 | Cycle 1(6): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:55:36 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4716474464579905 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4631465517241375 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5107322175732216 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.506817042606517 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0738473360655747 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.063108728943337 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.651348837209303 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.485414798206276 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.225415492957744 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.211273684210526 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.747935126582278 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.68435597189695 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.00953271028037 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.75888513513515 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.994860139860136 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758040540540532 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.026592505854792 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.802738095238111 ms/frame
07:55:36 INFO - PID 22010 |
07:55:36 INFO - PID 22010 | Cycle 1(7): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:56:22 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4876741293532336 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4635714285714294 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5146814752724227 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5033291614518136 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.077682051282051 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0777538461538474 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.366673944687046 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.301720430107525 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.560258358662616 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.486356502242154 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.762769841269838 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.6839227166276345 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.02774004683841 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.758327702702715 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010852803738327 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.828498293515358 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.944895833333333 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.805918367346922 ms/frame
07:56:22 INFO - PID 22010 |
07:56:22 INFO - PID 22010 | Cycle 1(8): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:57:08 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4859527754763877 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4612669126691262 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5128098827470686 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503391739674592 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0804106776180715 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.062963246554364 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.2858357142857155 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.264264392324091 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.231607898448523 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.1675729166666615 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.680195007800314 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.642470997679812 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.009649532710283 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.780135593220337 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.930173210161661 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7828983050847516 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.976662790697667 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.829112627986349 ms/frame
07:57:08 INFO - PID 22010 |
07:57:08 INFO - PID 22010 | Cycle 1(9): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:57:54 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4945220282626765 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.470012345679013 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5128810720268 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.497446941323347 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.086651234567902 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0586544342507653 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.4188807069219465 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.396615384615383 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.451721068249262 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.444711111111109 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.703675548589342 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673060747663551 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.0441431924882725 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.805646258503403 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.026803278688524 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.759087837837845 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.027060889929743 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.781983050847474 ms/frame
07:57:54 INFO - PID 22010 |
07:57:54 INFO - PID 22010 | Cycle 1(10): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:58:40 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4898464730290457 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.469654320987654 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5189798488664983 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5065476190476197 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0873816872427997 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.067484662576687 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.471162444113266 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.566426940639264 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.559984802431607 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.5156772009029424 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.801488000000001 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.7191273584905655 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.993834498834507 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.803843537414947 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.026182669789228 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.782830508474569 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.978476744186053 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.736616161616166 ms/frame
07:58:40 INFO - PID 22010 |
07:58:40 INFO - PID 22010 | Cycle 1(11): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:59:26 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4839072847682124 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460984009840098 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5127261306532667 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5039549436796005 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0834532374100707 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0678834355828224 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_startup = 4.335252890173409 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1_inclip = 4.301354838709679 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.202570028011202 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.202216386554624 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_startup = 4.807708333333337 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.480p.60fps.webm_scale_2_inclip = 4.751401425178147 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011635514018699 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.806071428571406 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.994289044289051 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.804897959183672 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.0267096018735336 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.781966101694916 ms/frame
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | Cycle 1(12): loaded http://localhost:44760/tests/video/video_playback.html (next: http://localhost:44760/tests/video/video_playback.html)
07:59:26 INFO - PID 22010 | __start_tp_report
07:59:26 INFO - PID 22010 | _x_x_mozilla_page_load
07:59:26 INFO - PID 22010 | _x_x_mozilla_page_load_details
07:59:26 INFO - PID 22010 | |i|pagename|runs|
07:59:26 INFO - PID 22010 | |0;240p.120fps.mp4_scale_1_startup;2.9413333333333336;2.4921677740863784;2.496247920133112;2.487786069651741;2.477803468208093;2.4839238410596027;2.4716474464579905;2.4876741293532336;2.4859527754763877;2.4945220282626765;2.4898464730290457;2.4839072847682124
07:59:26 INFO - PID 22010 | |1;240p.120fps.mp4_scale_1_inclip;2.4612792127921277;2.4574938574938576;2.46439039408867;2.460965559655597;2.4671208384710233;2.463669950738917;2.4631465517241375;2.4635714285714294;2.4612669126691262;2.470012345679013;2.469654320987654;2.460984009840098
07:59:26 INFO - PID 22010 | |2;240p.120fps.mp4_scale_1.1_startup;2.5148533109807203;2.5191015952980687;2.5065789473684212;2.5091889632107023;2.527982308340354;2.5239444911690496;2.5107322175732216;2.5146814752724227;2.5128098827470686;2.5128810720268;2.5189798488664983;2.5127261306532667
07:59:26 INFO - PID 22010 | |3;240p.120fps.mp4_scale_1.1_inclip;2.4943079800498746;2.5004250000000003;2.5035982478097623;2.512801507537688;2.504399249061327;2.507368421052633;2.506817042606517;2.5033291614518136;2.503391739674592;2.497446941323347;2.5065476190476197;2.5039549436796005
07:59:26 INFO - PID 22010 | |4;240p.120fps.mp4_scale_2_startup;3.0808008213552367;3.0776461538461537;3.0771179487179494;3.0778153846153855;3.0804722792607815;3.0776153846153838;3.0738473360655747;3.077682051282051;3.0804106776180715;3.086651234567902;3.0873816872427997;3.0834532374100707
07:59:26 INFO - PID 22010 | |5;240p.120fps.mp4_scale_2_inclip;3.073548387096773;3.0784153846153863;3.0628024502297095;3.0685122699386502;3.0721351766513045;3.073064516129033;3.063108728943337;3.0777538461538474;3.062963246554364;3.0586544342507653;3.067484662576687;3.0678834355828224
07:59:26 INFO - PID 22010 | |6;480p.60fps.webm_scale_1_startup;5.191098615916951;4.310890804597703;4.166958333333334;4.762809523809524;4.225267605633806;4.601556748466258;4.651348837209303;4.366673944687046;4.2858357142857155;4.4188807069219465;4.471162444113266;4.335252890173409
07:59:26 INFO - PID 22010 | |7;480p.60fps.webm_scale_1_inclip;4.821566265060243;4.176283924843421;4.159708939708939;5.038967254408063;4.151358921161822;4.526561085972846;4.485414798206276;4.301720430107525;4.264264392324091;4.396615384615383;4.566426940639264;4.301354838709679
07:59:26 INFO - PID 22010 | |8;480p.60fps.webm_scale_1.1_startup;4.243804809052333;4.2502620396600586;4.219690576652603;4.268001422475105;4.485306427503734;4.581541984732824;4.225415492957744;4.560258358662616;4.231607898448523;4.451721068249262;4.559984802431607;4.202570028011202
07:59:26 INFO - PID 22010 | |9;480p.60fps.webm_scale_1.1_inclip;4.175323590814203;4.1940775681341655;4.1844769874477;4.21198947368421;4.454265033407573;4.537040816326528;4.211273684210526;4.486356502242154;4.1675729166666615;4.444711111111109;4.5156772009029424;4.202216386554624
07:59:26 INFO - PID 22010 | |10;480p.60fps.webm_scale_2_startup;4.629537037037041;4.725834645669296;4.739533965244866;4.702617554858935;4.638276661514683;4.680366614664589;4.747935126582278;4.762769841269838;4.680195007800314;4.703675548589342;4.801488000000001;4.807708333333337
07:59:26 INFO - PID 22010 | |11;480p.60fps.webm_scale_2_inclip;4.65253488372093;4.683817330210768;4.663566433566438;4.718643867924532;4.642250580046407;4.673714953271024;4.68435597189695;4.6839227166276345;4.642470997679812;4.673060747663551;4.7191273584905655;4.751401425178147
07:59:26 INFO - PID 22010 | |12;1080p.60fps.mp4_scale_1_startup;7.044448356807506;7.010280373831771;7.042417840375594;7.010572429906537;7.0100116822429985;6.979046511627911;7.00953271028037;7.02774004683841;7.009649532710283;7.0441431924882725;6.993834498834507;7.011635514018699
07:59:26 INFO - PID 22010 | |13;1080p.60fps.mp4_scale_1_inclip;6.757567567567586;6.850719178082204;6.780067796610154;6.757432432432447;6.78296610169491;6.758040540540532;6.75888513513515;6.758327702702715;6.780135593220337;6.805646258503403;6.803843537414947;6.806071428571406
07:59:26 INFO - PID 22010 | |14;1080p.60fps.mp4_scale_1.1_startup;6.944456018518513;6.979151162790689;6.976627906976738;7.010070093457938;6.960580046403717;6.961218097447804;6.994860139860136;7.010852803738327;6.930173210161661;7.026803278688524;7.026182669789228;6.994289044289051
07:59:26 INFO - PID 22010 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.736195286195291;6.759898648648649;6.781542372881357;6.804489795918381;6.737306397306384;6.804574829931967;6.758040540540532;6.828498293515358;6.7828983050847516;6.759087837837845;6.782830508474569;6.804897959183672
07:59:26 INFO - PID 22010 | |16;1080p.60fps.mp4_scale_2_startup;6.962749419953596;7.026276346604218;6.96280742459397;6.930877598152424;6.977174418604646;6.97682558139534;7.026592505854792;6.944895833333333;6.976662790697667;7.027060889929743;6.978476744186053;7.0267096018735336
07:59:26 INFO - PID 22010 | |17;1080p.60fps.mp4_scale_2_inclip;6.758327702702691;6.7344781144781125;6.804149659863939;6.7829152542372855;6.828344709897617;6.803027210884367;6.802738095238111;6.805918367346922;6.829112627986349;6.781983050847474;6.736616161616166;6.781966101694916
07:59:26 INFO - PID 22010 | __end_tp_report
07:59:26 INFO - PID 22010 | __start_cc_report
07:59:26 INFO - PID 22010 | _x_x_mozilla_cycle_collect,616
07:59:26 INFO - PID 22010 | __end_cc_report
07:59:26 INFO - PID 22010 | __startTimestamp1510847966621__endTimestamp
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | ------- Summary: start -------
07:59:26 INFO - PID 22010 | Number of tests: 18
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.52 Median:2.49 stddev:0.13 (5.3%) stddev-sans-first:0.01
07:59:26 INFO - PID 22010 | Values: 2.9 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.46 Median:2.46 stddev:0.00 (0.2%) stddev-sans-first:0.00
07:59:26 INFO - PID 22010 | 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
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.52 Median:2.51 stddev:0.01 (0.2%) stddev-sans-first:0.01
07:59:26 INFO - PID 22010 | 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
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.50 Median:2.50 stddev:0.00 (0.2%) stddev-sans-first:0.00
07:59:26 INFO - PID 22010 | 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
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.08 Median:3.08 stddev:0.00 (0.1%) stddev-sans-first:0.00
07:59:26 INFO - PID 22010 | 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
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.07 Median:3.07 stddev:0.01 (0.2%) stddev-sans-first:0.01
07:59:26 INFO - PID 22010 | 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
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.48 Median:4.45 stddev:0.29 (6.4%) stddev-sans-first:0.19
07:59:26 INFO - PID 22010 | Values: 5.2 4.3 4.2 4.8 4.2 4.6 4.7 4.4 4.3 4.4 4.5 4.3
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.43 Median:4.44 stddev:0.27 (6.2%) stddev-sans-first:0.26
07:59:26 INFO - PID 22010 | Values: 4.8 4.2 4.2 5.0 4.2 4.5 4.5 4.3 4.3 4.4 4.6 4.3
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.36 Median:4.36 stddev:0.16 (3.6%) stddev-sans-first:0.16
07:59:26 INFO - PID 22010 | Values: 4.2 4.3 4.2 4.3 4.5 4.6 4.2 4.6 4.2 4.5 4.6 4.2
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.32 Median:4.33 stddev:0.15 (3.6%) stddev-sans-first:0.16
07:59:26 INFO - PID 22010 | Values: 4.2 4.2 4.2 4.2 4.5 4.5 4.2 4.5 4.2 4.4 4.5 4.2
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.72 Median:4.73 stddev:0.06 (1.2%) stddev-sans-first:0.05
07:59:26 INFO - PID 22010 | Values: 4.6 4.7 4.7 4.7 4.6 4.7 4.7 4.8 4.7 4.7 4.8 4.8
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.68 Median:4.68 stddev:0.03 (0.7%) stddev-sans-first:0.03
07:59:26 INFO - PID 22010 | Values: 4.7 4.7 4.7 4.7 4.6 4.7 4.7 4.7 4.6 4.7 4.7 4.8
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.02 Median:7.01 stddev:0.02 (0.3%) stddev-sans-first:0.02
07:59:26 INFO - PID 22010 | 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
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.78 Median:6.78 stddev:0.03 (0.4%) stddev-sans-first:0.03
07:59:26 INFO - PID 22010 | Values: 6.8 6.9 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:6.98 Median:6.99 stddev:0.03 (0.4%) stddev-sans-first:0.03
07:59:26 INFO - PID 22010 | Values: 6.9 7.0 7.0 7.0 7.0 7.0 7.0 7.0 6.9 7.0 7.0 7.0
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.78 Median:6.78 stddev:0.03 (0.4%) stddev-sans-first:0.03
07:59:26 INFO - PID 22010 | Values: 6.7 6.8 6.8 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8 6.8
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:6.98 Median:6.98 stddev:0.03 (0.5%) stddev-sans-first:0.03
07:59:26 INFO - PID 22010 | Values: 7.0 7.0 7.0 6.9 7.0 7.0 7.0 6.9 7.0 7.0 7.0 7.0
07:59:26 INFO - PID 22010 |
07:59:26 INFO - PID 22010 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.79 Median:6.80 stddev:0.03 (0.5%) stddev-sans-first:0.03
07:59:26 INFO - PID 22010 | Values: 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.7 6.8
07:59:26 INFO - PID 22010 | -------- Summary: end --------
07:59:26 INFO - PID 22010 |
07:59:27 INFO - TEST-INFO | 22010: exit 0
07:59:27 INFO - TEST-OK | basic_compositor_video | took 562532ms
07:59:27 INFO - TEST-START | glvideo
07:59:27 INFO - Initialising browser for glvideo test...
07:59:27 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
07:59:27 INFO - Installing Add-ons
07:59:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44760/getInfo.html -profile /tmp/tmp4VpyAb/profile
07:59:27 INFO - TEST-INFO | started process 23297 (/builds/slave/test/build/application/firefox/firefox http://localhost:44760/getInfo.html)
07:59:33 INFO - TEST-INFO | 23297: exit 0
07:59:33 INFO - Browser initialized.
07:59:33 INFO - Running cycle 1/1 for glvideo test...
07:59:33 INFO - Using env: {'DISPLAY': ':0',
07:59:33 INFO - 'HOME': '/home/cltbld',
07:59:33 INFO - 'JSGC_DISABLE_POISONING': '1',
07:59:33 INFO - 'LANG': 'en_US.UTF-8',
07:59:33 INFO - 'LANGUAGE': 'en_US:en',
07:59:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
07:59:33 INFO - 'LOGNAME': 'cltbld',
07:59:33 INFO - 'MAIL': '/var/mail/cltbld',
07:59:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
07:59:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
07:59:33 INFO - 'MOZ_ACCELERATED': '1',
07:59:33 INFO - 'MOZ_CRASHREPORTER': '1',
07:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
07:59:33 INFO - 'MOZ_NO_REMOTE': '1',
07:59:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
07:59:33 INFO - 'MOZ_WEBRENDER': '1',
07:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:33 INFO - 'NO_EM_RESTART': '1',
07:59:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:33 INFO - 'PWD': '/builds/slave/test',
07:59:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
07:59:33 INFO - 'RUST_BACKTRACE': 'full',
07:59:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
07:59:33 INFO - 'SHELL': '/bin/bash',
07:59:33 INFO - 'SHLVL': '1',
07:59:33 INFO - 'TERM': 'linux',
07:59:33 INFO - 'TMOUT': '86400',
07:59:33 INFO - 'USER': 'cltbld',
07:59:33 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:33 INFO - '_': '/tools/buildbot/bin/python'}
07:59:33 INFO - TEST-INFO | started process 23576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4VpyAb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
07:59:34 INFO - PID 23576 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
07:59:34 INFO - PID 23576 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
07:59:35 INFO - PID 23576 |
07:59:35 INFO - PID 23576 | (/builds/slave/test/build/application/firefox/firefox:23638): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
07:59:35 INFO - PID 23576 |
07:59:36 INFO - PID 23576 | [talos glvideo result] Mean tick time across 100 ticks: 6.861999999999999 ms
07:59:36 INFO - PID 23576 | Cycle 1(1): loaded http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html)
07:59:37 INFO - PID 23576 | [talos glvideo result] Mean tick time across 100 ticks: 6.55595 ms
07:59:37 INFO - PID 23576 | Cycle 1(2): loaded http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html)
07:59:39 INFO - PID 23576 | [talos glvideo result] Mean tick time across 100 ticks: 6.4792499999999995 ms
07:59:39 INFO - PID 23576 | Cycle 1(3): loaded http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html)
07:59:40 INFO - PID 23576 | [talos glvideo result] Mean tick time across 100 ticks: 6.670000000000001 ms
07:59:40 INFO - PID 23576 | Cycle 1(4): loaded http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html)
07:59:41 INFO - PID 23576 | [talos glvideo result] Mean tick time across 100 ticks: 6.68105 ms
07:59:41 INFO - PID 23576 | Cycle 1(5): loaded http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:44760/tests/webgl/benchmarks/video/video_upload.html)
07:59:42 INFO - PID 23576 | __start_tp_report
07:59:42 INFO - PID 23576 | _x_x_mozilla_page_load
07:59:42 INFO - PID 23576 | _x_x_mozilla_page_load_details
07:59:42 INFO - PID 23576 | |i|pagename|runs|
07:59:42 INFO - PID 23576 | |0;Mean tick time across 100 ticks: ;6.861999999999999;6.55595;6.4792499999999995;6.670000000000001;6.68105
07:59:42 INFO - PID 23576 | __end_tp_report
07:59:42 INFO - PID 23576 | __start_cc_report
07:59:42 INFO - PID 23576 | _x_x_mozilla_cycle_collect,282
07:59:42 INFO - PID 23576 | __end_cc_report
07:59:42 INFO - PID 23576 | __startTimestamp1510847982140__endTimestamp
07:59:42 INFO - PID 23576 |
07:59:42 INFO - PID 23576 | ------- Summary: start -------
07:59:42 INFO - PID 23576 | Number of tests: 1
07:59:42 INFO - PID 23576 |
07:59:42 INFO - PID 23576 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.65 Median:6.67 stddev:0.15 (2.2%) stddev-sans-first:0.10
07:59:42 INFO - PID 23576 | Values: 6.9 6.6 6.5 6.7 6.7
07:59:42 INFO - PID 23576 | -------- Summary: end --------
07:59:42 INFO - PID 23576 |
07:59:42 INFO - PID 23576 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
07:59:42 INFO - TEST-INFO | 23576: exit 0
07:59:42 INFO - TEST-OK | glvideo | took 15419ms
07:59:42 INFO - TEST-START | displaylist_mutate
07:59:42 INFO - Initialising browser for displaylist_mutate test...
07:59:42 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
07:59:42 INFO - Installing Add-ons
07:59:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:44760/getInfo.html -profile /tmp/tmpztllvM/profile
07:59:42 INFO - TEST-INFO | started process 23809 (/builds/slave/test/build/application/firefox/firefox http://localhost:44760/getInfo.html)
07:59:48 INFO - TEST-INFO | 23809: exit 0
07:59:49 INFO - Browser initialized.
07:59:49 INFO - Running cycle 1/1 for displaylist_mutate test...
07:59:49 INFO - Using env: {'DISPLAY': ':0',
07:59:49 INFO - 'HOME': '/home/cltbld',
07:59:49 INFO - 'JSGC_DISABLE_POISONING': '1',
07:59:49 INFO - 'LANG': 'en_US.UTF-8',
07:59:49 INFO - 'LANGUAGE': 'en_US:en',
07:59:49 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
07:59:49 INFO - 'LOGNAME': 'cltbld',
07:59:49 INFO - 'MAIL': '/var/mail/cltbld',
07:59:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
07:59:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
07:59:49 INFO - 'MOZ_ACCELERATED': '1',
07:59:49 INFO - 'MOZ_CRASHREPORTER': '1',
07:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
07:59:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
07:59:49 INFO - 'MOZ_NO_REMOTE': '1',
07:59:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
07:59:49 INFO - 'MOZ_WEBRENDER': '1',
07:59:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
07:59:49 INFO - 'NO_EM_RESTART': '1',
07:59:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
07:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
07:59:49 INFO - 'PWD': '/builds/slave/test',
07:59:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
07:59:49 INFO - 'RUST_BACKTRACE': 'full',
07:59:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
07:59:49 INFO - 'SHELL': '/bin/bash',
07:59:49 INFO - 'SHLVL': '1',
07:59:49 INFO - 'TERM': 'linux',
07:59:49 INFO - 'TMOUT': '86400',
07:59:49 INFO - 'USER': 'cltbld',
07:59:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319',
07:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
07:59:49 INFO - '_': '/tools/buildbot/bin/python'}
07:59:49 INFO - TEST-INFO | started process 24090 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpztllvM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
07:59:49 INFO - PID 24090 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
07:59:50 INFO - PID 24090 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
07:59:50 INFO - PID 24090 |
07:59:50 INFO - PID 24090 | (/builds/slave/test/build/application/firefox/firefox:24153): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
07:59:50 INFO - PID 24090 |
08:00:14 INFO - PID 24090 | Cycle 1(1): loaded http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html)
08:00:38 INFO - PID 24090 | Cycle 1(2): loaded http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html)
08:01:02 INFO - PID 24090 | Cycle 1(3): loaded http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html)
08:01:27 INFO - PID 24090 | Cycle 1(4): loaded http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html)
08:01:27 INFO - PID 24090 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
08:01:52 INFO - PID 24090 | Cycle 1(5): loaded http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:44760/tests/layout/benchmarks/displaylist_mutate.html)
08:01:52 INFO - PID 24090 | __start_tp_report
08:01:52 INFO - PID 24090 | _x_x_mozilla_page_load
08:01:52 INFO - PID 24090 | _x_x_mozilla_page_load_details
08:01:52 INFO - PID 24090 | |i|pagename|runs|
08:01:52 INFO - PID 24090 | |0;/displaylist_mutate.html;23103.8;23459.53;23589.655;23919.595;23797.04
08:01:52 INFO - PID 24090 | __end_tp_report
08:01:52 INFO - PID 24090 | __start_cc_report
08:01:52 INFO - PID 24090 | _x_x_mozilla_cycle_collect,221
08:01:52 INFO - PID 24090 | __end_cc_report
08:01:52 INFO - PID 24090 | __startTimestamp1510848112254__endTimestamp
08:01:52 INFO - PID 24090 |
08:01:52 INFO - PID 24090 | ------- Summary: start -------
08:01:52 INFO - PID 24090 | Number of tests: 1
08:01:52 INFO - PID 24090 |
08:01:52 INFO - PID 24090 | [#0] /displaylist_mutate.html Cycles:5 Average:23573.92 Median:23589.65 stddev:317.66 (1.3%) stddev-sans-first:206.03
08:01:52 INFO - PID 24090 | Values: 23103.8 23459.5 23589.7 23919.6 23797.0
08:01:52 INFO - PID 24090 | -------- Summary: end --------
08:01:52 INFO - PID 24090 |
08:01:53 INFO - TEST-INFO | 24090: exit 0
08:01:53 INFO - TEST-OK | displaylist_mutate | took 130186ms
08:01:53 INFO - SUITE-END | took 708s
08:01:53 INFO - Completed test suite (00:11:48)
08:01:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.397851543900306, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.9413333333333336, 2.4921677740863784, 2.496247920133112, 2.487786069651741, 2.477803468208093, 2.4839238410596027, 2.4716474464579905, 2.4876741293532336, 2.4859527754763877, 2.4945220282626765, 2.4898464730290457, 2.4839072847682124], "value": 2.4876741293532336, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4612792127921277, 2.4574938574938576, 2.46439039408867, 2.460965559655597, 2.4671208384710233, 2.463669950738917, 2.4631465517241375, 2.4635714285714294, 2.4612669126691262, 2.470012345679013, 2.469654320987654, 2.460984009840098], "value": 2.4635714285714294, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.5148533109807203, 2.5191015952980687, 2.5065789473684212, 2.5091889632107023, 2.527982308340354, 2.5239444911690496, 2.5107322175732216, 2.5146814752724227, 2.5128098827470686, 2.5128810720268, 2.5189798488664983, 2.5127261306532667], "value": 2.5128810720268, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4943079800498746, 2.5004250000000003, 2.5035982478097623, 2.512801507537688, 2.504399249061327, 2.507368421052633, 2.506817042606517, 2.5033291614518136, 2.503391739674592, 2.497446941323347, 2.5065476190476197, 2.5039549436796005], "value": 2.5039549436796005, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0808008213552367, 3.0776461538461537, 3.0771179487179494, 3.0778153846153855, 3.0804722792607815, 3.0776153846153838, 3.0738473360655747, 3.077682051282051, 3.0804106776180715, 3.086651234567902, 3.0873816872427997, 3.0834532374100707], "value": 3.0778153846153855, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.073548387096773, 3.0784153846153863, 3.0628024502297095, 3.0685122699386502, 3.0721351766513045, 3.073064516129033, 3.063108728943337, 3.0777538461538474, 3.062963246554364, 3.0586544342507653, 3.067484662576687, 3.0678834355828224], "value": 3.0678834355828224, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.191098615916951, 4.310890804597703, 4.166958333333334, 4.762809523809524, 4.225267605633806, 4.601556748466258, 4.651348837209303, 4.366673944687046, 4.2858357142857155, 4.4188807069219465, 4.471162444113266, 4.335252890173409], "value": 4.366673944687046, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.821566265060243, 4.176283924843421, 4.159708939708939, 5.038967254408063, 4.151358921161822, 4.526561085972846, 4.485414798206276, 4.301720430107525, 4.264264392324091, 4.396615384615383, 4.566426940639264, 4.301354838709679], "value": 4.301720430107525, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.243804809052333, 4.2502620396600586, 4.219690576652603, 4.268001422475105, 4.485306427503734, 4.581541984732824, 4.225415492957744, 4.560258358662616, 4.231607898448523, 4.451721068249262, 4.559984802431607, 4.202570028011202], "value": 4.268001422475105, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.175323590814203, 4.1940775681341655, 4.1844769874477, 4.21198947368421, 4.454265033407573, 4.537040816326528, 4.211273684210526, 4.486356502242154, 4.1675729166666615, 4.444711111111109, 4.5156772009029424, 4.202216386554624], "value": 4.21198947368421, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.629537037037041, 4.725834645669296, 4.739533965244866, 4.702617554858935, 4.638276661514683, 4.680366614664589, 4.747935126582278, 4.762769841269838, 4.680195007800314, 4.703675548589342, 4.801488000000001, 4.807708333333337], "value": 4.725834645669296, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.65253488372093, 4.683817330210768, 4.663566433566438, 4.718643867924532, 4.642250580046407, 4.673714953271024, 4.68435597189695, 4.6839227166276345, 4.642470997679812, 4.673060747663551, 4.7191273584905655, 4.751401425178147], "value": 4.683817330210768, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.044448356807506, 7.010280373831771, 7.042417840375594, 7.010572429906537, 7.0100116822429985, 6.979046511627911, 7.00953271028037, 7.02774004683841, 7.009649532710283, 7.0441431924882725, 6.993834498834507, 7.011635514018699], "value": 7.010280373831771, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.757567567567586, 6.850719178082204, 6.780067796610154, 6.757432432432447, 6.78296610169491, 6.758040540540532, 6.75888513513515, 6.758327702702715, 6.780135593220337, 6.805646258503403, 6.803843537414947, 6.806071428571406], "value": 6.780135593220337, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.944456018518513, 6.979151162790689, 6.976627906976738, 7.010070093457938, 6.960580046403717, 6.961218097447804, 6.994860139860136, 7.010852803738327, 6.930173210161661, 7.026803278688524, 7.026182669789228, 6.994289044289051], "value": 6.994289044289051, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.736195286195291, 6.759898648648649, 6.781542372881357, 6.804489795918381, 6.737306397306384, 6.804574829931967, 6.758040540540532, 6.828498293515358, 6.7828983050847516, 6.759087837837845, 6.782830508474569, 6.804897959183672], "value": 6.782830508474569, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.962749419953596, 7.026276346604218, 6.96280742459397, 6.930877598152424, 6.977174418604646, 6.97682558139534, 7.026592505854792, 6.944895833333333, 6.976662790697667, 7.027060889929743, 6.978476744186053, 7.0267096018735336], "value": 6.977174418604646, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.758327702702691, 6.7344781144781125, 6.804149659863939, 6.7829152542372855, 6.828344709897617, 6.803027210884367, 6.802738095238111, 6.805918367346922, 6.829112627986349, 6.781983050847474, 6.736616161616166, 6.781966101694916], "value": 6.802738095238111, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.861999999999999, 6.55595, 6.4792499999999995, 6.670000000000001, 6.68105], "value": 6.6129750000000005, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [23103.8, 23459.53, 23589.655, 23919.595, 23797.04], "value": 23693.3475, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
08:01:53 INFO - Return code: 0
08:01:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
08:01:53 INFO - # TBPL SUCCESS #
08:01:53 INFO - Running post-action listener: _package_coverage_data
08:01:53 INFO - Running post-action listener: _resource_record_post_action
08:01:53 INFO - [mozharness: 2017-11-16 16:01:53.694974Z] Finished run-tests step (success)
08:01:53 INFO - Running post-run listener: _resource_record_post_run
08:01:53 INFO - Total resource usage - Wall time: 728s; CPU: 41.0%; Read bytes: 14127104; Write bytes: 352260096; Read time: 8264; Write time: 212736
08:01:53 INFO - TinderboxPrint: CPU usage
40.9%
08:01:53 INFO - TinderboxPrint: I/O read bytes / time
14,127,104 / 8,264
08:01:53 INFO - TinderboxPrint: I/O write bytes / time
352,260,096 / 212,736
08:01:53 INFO - TinderboxPrint: CPU idle
3,240.3 (58.9%)
08:01:53 INFO - TinderboxPrint: CPU system
648.6 (11.8%)
08:01:53 INFO - TinderboxPrint: CPU user
1,586.2 (28.8%)
08:01:53 INFO - TinderboxPrint: Swap in / out
0 / 0
08:01:53 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 7700480; Read time: 0; Write time: 1044
08:01:53 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
08:01:53 INFO - run-tests - Wall time: 709s; CPU: 42.0%; Read bytes: 14127104; Write bytes: 344559616; Read time: 8264; Write time: 211692
08:01:54 INFO - Running post-run listener: _upload_blobber_files
08:01:54 INFO - Blob upload gear active.
08:01:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
08:01:54 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
08:01:54 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']
08:01:54 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
08:01:54 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
08:01:54 INFO - SNIMissingWarning
08:01:54 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
08:01:54 INFO - InsecurePlatformWarning
08:01:54 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.)
08:01:54 INFO - SubjectAltNameWarning
08:01:54 INFO - (blobuploader) - INFO - Open directory for files ...
08:01:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
08:01:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:01:54 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:01:54 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
08:01:54 INFO - InsecurePlatformWarning
08:01:54 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.)
08:01:54 INFO - SubjectAltNameWarning
08:01:55 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
08:01:55 INFO - InsecurePlatformWarning
08:01:55 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
08:01:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:01:55 INFO - (blobuploader) - INFO - Done attempting.
08:01:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
08:01:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:01:55 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:01:55 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
08:01:55 INFO - InsecurePlatformWarning
08:01:55 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.)
08:01:55 INFO - SubjectAltNameWarning
08:02: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
08:02:00 INFO - InsecurePlatformWarning
08:02:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:02:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:02:00 INFO - (blobuploader) - INFO - Done attempting.
08:02:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
08:02:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:02:00 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:02: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
08:02:00 INFO - InsecurePlatformWarning
08:02: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.)
08:02:00 INFO - SubjectAltNameWarning
08:02: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
08:02:01 INFO - InsecurePlatformWarning
08:02:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
08:02:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:02:01 INFO - (blobuploader) - INFO - Done attempting.
08:02:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
08:02:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:02:01 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:02: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
08:02:01 INFO - InsecurePlatformWarning
08:02: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.)
08:02:01 INFO - SubjectAltNameWarning
08:02:02 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
08:02:02 INFO - InsecurePlatformWarning
08:02:02 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
08:02:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:02:02 INFO - (blobuploader) - INFO - Done attempting.
08:02:02 INFO - (blobuploader) - INFO - Iteration through files over.
08:02:02 INFO - Return code: 0
08:02:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
08:02:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
08:02:02 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c29329aba7e52ab4765794ec5e96cc01f3f65d2128bc56af748bc6c91df31e2a8baa93911d4d03b177b2fc5122666c030d5b092dd078fd3a24d2ff5cbd7f6e0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/276d8d8f970c6db6d65605059858db03faef1e8759bf7ee20cf777a89b387bbb44721c85d98890a3174eecbe046e0420275754f1d781b015afc67d8a90603a50", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baff64bc5e62d6d3ff732610778d6928bfc2d7269240a038a811daf64f4632b2f0e31f281d9e9a6169db8af79e76f0a8777da7845c726c3d15d77688e6d800d6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd03e16cc87a1d53022002bc7ea7cb96dd2cebe5cd8cbd18d388b5103c0c1b3bf6697019eecdb2178f8049594646d88752f40d50b1089c33c1a8f04e58539625"}
08:02:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
08:02:02 INFO - Writing to file /builds/slave/test/properties/blobber_files
08:02:02 INFO - Contents:
08:02:02 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c29329aba7e52ab4765794ec5e96cc01f3f65d2128bc56af748bc6c91df31e2a8baa93911d4d03b177b2fc5122666c030d5b092dd078fd3a24d2ff5cbd7f6e0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/276d8d8f970c6db6d65605059858db03faef1e8759bf7ee20cf777a89b387bbb44721c85d98890a3174eecbe046e0420275754f1d781b015afc67d8a90603a50", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baff64bc5e62d6d3ff732610778d6928bfc2d7269240a038a811daf64f4632b2f0e31f281d9e9a6169db8af79e76f0a8777da7845c726c3d15d77688e6d800d6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd03e16cc87a1d53022002bc7ea7cb96dd2cebe5cd8cbd18d388b5103c0c1b3bf6697019eecdb2178f8049594646d88752f40d50b1089c33c1a8f04e58539625"}
08:02:02 INFO - Running post-run listener: copy_logs_to_upload_dir
08:02:02 INFO - Copying logs to upload dir...
08:02:02 INFO - mkdir: /builds/slave/test/build/upload/logs
08:02:02 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=795.345643
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 13 mins, 15 secs) (at 2017-11-16 08:02:02.405708) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-16 08:02:02.409806) =========
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=56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c29329aba7e52ab4765794ec5e96cc01f3f65d2128bc56af748bc6c91df31e2a8baa93911d4d03b177b2fc5122666c030d5b092dd078fd3a24d2ff5cbd7f6e0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/276d8d8f970c6db6d65605059858db03faef1e8759bf7ee20cf777a89b387bbb44721c85d98890a3174eecbe046e0420275754f1d781b015afc67d8a90603a50", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baff64bc5e62d6d3ff732610778d6928bfc2d7269240a038a811daf64f4632b2f0e31f281d9e9a6169db8af79e76f0a8777da7845c726c3d15d77688e6d800d6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd03e16cc87a1d53022002bc7ea7cb96dd2cebe5cd8cbd18d388b5103c0c1b3bf6697019eecdb2178f8049594646d88752f40d50b1089c33c1a8f04e58539625"}
build_url:https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011382
build_url: 'https://queue.taskcluster.net/v1/task/MrN9yGWBREWQ-0lCfud7HA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c29329aba7e52ab4765794ec5e96cc01f3f65d2128bc56af748bc6c91df31e2a8baa93911d4d03b177b2fc5122666c030d5b092dd078fd3a24d2ff5cbd7f6e0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/276d8d8f970c6db6d65605059858db03faef1e8759bf7ee20cf777a89b387bbb44721c85d98890a3174eecbe046e0420275754f1d781b015afc67d8a90603a50", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/baff64bc5e62d6d3ff732610778d6928bfc2d7269240a038a811daf64f4632b2f0e31f281d9e9a6169db8af79e76f0a8777da7845c726c3d15d77688e6d800d6", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd03e16cc87a1d53022002bc7ea7cb96dd2cebe5cd8cbd18d388b5103c0c1b3bf6697019eecdb2178f8049594646d88752f40d50b1089c33c1a8f04e58539625"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-16 08:02:02.439915) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-16 08:02:02.440303) =========
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=56c518bb7cbc29fb3c9463e2000001c4-1510839975.336012-2003279319
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004565
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-16 08:02:02.524478) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-16 08:02:02.524797) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-16 08:02:02.525098) =========
========= Total master_lag: 0.24 =========