builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-053
starttime: 1512397927.33
results: success (0)
revision: 195bb467e6cb5c8c5f5fb2858c0a55b2d0b9552d
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.326926) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.327343) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.327612) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.364350) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.364655) =========
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=920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-12-04 06:32:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 85.6M=0s
2017-12-04 06:32:07 (85.6 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.163677
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.551011) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.551408) =========
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=920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.015001
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:07.601265) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-04 06:32:07.601652) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 195bb467e6cb5c8c5f5fb2858c0a55b2d0b9552d --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 195bb467e6cb5c8c5f5fb2858c0a55b2d0b9552d --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=920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-12-04 06:32:07,663 truncating revision to first 12 chars
2017-12-04 06:32:07,663 Setting DEBUG logging.
2017-12-04 06:32:07,663 attempt 1/10
2017-12-04 06:32:07,663 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/195bb467e6cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-12-04 06:32:08,498 unpacking tar archive at: mozilla-central-195bb467e6cb/testing/mozharness/
program finished with exit code 0
elapsedTime=1.490675
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-04 06:32:09.109830) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:09.110197) =========
script_repo_revision: 195bb467e6cb5c8c5f5fb2858c0a55b2d0b9552d
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:09.110681) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:09.110999) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-04 06:32:09.126178) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 16 secs) (at 2017-12-04 06:32:09.126533) =========
/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=920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
06:32:09 INFO - MultiFileLogger online at 20171204 06:32:09 in /builds/slave/test
06:32:09 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
06:32:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
06:32:09 INFO - {'append_to_log': False,
06:32:09 INFO - 'base_work_dir': '/builds/slave/test',
06:32:09 INFO - 'blob_upload_branch': 'Firefox',
06:32:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
06:32:09 INFO - 'branch': 'Firefox',
06:32:09 INFO - 'buildbot_json_path': 'buildprops.json',
06:32:09 INFO - 'code_coverage': False,
06:32:09 INFO - 'config_files': ('talos/linux_config.py',),
06:32:09 INFO - 'default_actions': ('clobber',
06:32:09 INFO - 'read-buildbot-config',
06:32:09 INFO - 'download-and-extract',
06:32:09 INFO - 'populate-webroot',
06:32:09 INFO - 'create-virtualenv',
06:32:09 INFO - 'install',
06:32:09 INFO - 'setup-mitmproxy',
06:32:09 INFO - 'run-tests'),
06:32:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
06:32:09 INFO - 'disable_ccov_upload': False,
06:32:09 INFO - 'disable_stylo': False,
06:32:09 INFO - 'download_minidump_stackwalk': True,
06:32:09 INFO - 'download_symbols': 'ondemand',
06:32:09 INFO - 'e10s': False,
06:32:09 INFO - 'enable_stylo': False,
06:32:09 INFO - 'enable_webrender': False,
06:32:09 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
06:32:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
06:32:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
06:32:09 INFO - 'gecko_profile': False,
06:32:09 INFO - 'gecko_profile_interval': 0,
06:32:09 INFO - 'installer_path': 'installer.exe',
06:32:09 INFO - 'jsd_code_coverage': False,
06:32:09 INFO - 'log_level': 'info',
06:32:09 INFO - 'log_name': 'talos',
06:32:09 INFO - 'log_to_console': True,
06:32:09 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
06:32:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
06:32:09 INFO - 'opt_config_files': (),
06:32:09 INFO - 'pip_index': False,
06:32:09 INFO - 'suite': 'g4-e10s',
06:32:09 INFO - 'system_bits': '32',
06:32:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
06:32:09 INFO - 'title': 'talos-linux64-ix-053',
06:32:09 INFO - 'tooltool_cache': '/builds/tooltool_cache',
06:32:09 INFO - 'use_talos_json': True,
06:32:09 INFO - 'verify': 'False',
06:32:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
06:32:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
06:32:09 INFO - 'work_dir': 'build'}
06:32:09 INFO - [mozharness: 2017-12-04 14:32:09.392599Z] Running clobber step.
06:32:09 INFO - Running pre-action listener: _resource_record_pre_action
06:32:09 INFO - Running main action method: clobber
06:32:09 INFO - rmtree: /builds/slave/test/build
06:32:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
06:32:10 INFO - Running post-action listener: _resource_record_post_action
06:32:10 INFO - [mozharness: 2017-12-04 14:32:10.389565Z] Finished clobber step (success)
06:32:10 INFO - [mozharness: 2017-12-04 14:32:10.389722Z] Running read-buildbot-config step.
06:32:10 INFO - Running pre-action listener: _resource_record_pre_action
06:32:10 INFO - Running main action method: read_buildbot_config
06:32:10 INFO - Using buildbot properties:
06:32:10 INFO - {
06:32:10 INFO - "project": "",
06:32:10 INFO - "product": "firefox",
06:32:10 INFO - "who": "dluca@mozilla.com",
06:32:10 INFO - "installer_path": "public/build/target.tar.bz2",
06:32:10 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
06:32:10 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
06:32:10 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
06:32:10 INFO - "stage_platform": "linux64-qr",
06:32:10 INFO - "basedir": "/builds/slave/test",
06:32:10 INFO - "buildnumber": 227,
06:32:10 INFO - "platform": "ubuntu64_hw_qr",
06:32:10 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
06:32:10 INFO - "slavebuilddir": "test",
06:32:10 INFO - "taskId": "XwhJ6aJhRnK_1hWMJlowKg",
06:32:10 INFO - "branch": "mozilla-central",
06:32:10 INFO - "script_repo_revision": "production",
06:32:10 INFO - "revision": "195bb467e6cb5c8c5f5fb2858c0a55b2d0b9552d",
06:32:10 INFO - "slavename": "talos-linux64-ix-053",
06:32:10 INFO - "repo_path": "mozilla-central"
06:32:10 INFO - }
06:32:10 INFO - Finding installer, test and symbols from parent task.
06:32:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XwhJ6aJhRnK_1hWMJlowKg'}, attempt #1
06:32:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XwhJ6aJhRnK_1hWMJlowKg'}, attempt #1
06:32:11 INFO - Task dependencies: Jt3P76azQ6KJOSt0y_qSJQ
06:32:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ'}, attempt #1
06:32:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2
06:32:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.test_packages.json
06:32:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.crashreporter-symbols.zip
06:32:11 INFO - Running post-action listener: _resource_record_post_action
06:32:11 INFO - [mozharness: 2017-12-04 14:32:11.812230Z] Finished read-buildbot-config step (success)
06:32:11 INFO - [mozharness: 2017-12-04 14:32:11.812452Z] Running download-and-extract step.
06:32:11 INFO - Running pre-action listener: _resource_record_pre_action
06:32:11 INFO - Running main action method: download_and_extract
06:32:11 INFO - mkdir: /builds/slave/test/build/tests
06:32: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')]}
06:32:11 INFO - trying https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.test_packages.json
06:32:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
06:32:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
06:32:12 INFO - Downloaded 989 bytes.
06:32:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
06:32:12 INFO - Using the following test package requirements:
06:32:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
06:32:12 INFO - u'common': [u'target.common.tests.zip'],
06:32:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
06:32:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
06:32:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
06:32:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
06:32:12 INFO - u'mozbase': [u'target.common.tests.zip'],
06:32:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
06:32:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
06:32:12 INFO - u'web-platform': [u'target.common.tests.zip',
06:32:12 INFO - u'target.web-platform.tests.tar.gz'],
06:32:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
06:32:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
06:32:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.common.tests.zip
06:32:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
06:32:12 INFO - Fetch https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.common.tests.zip into memory
06:32:13 INFO - Content-Length response header: 59345326
06:32:13 INFO - Bytes received: 59345326
06:32:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.talos.tests.zip
06:32:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
06:32:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.talos.tests.zip into memory
06:32:25 INFO - Content-Length response header: 18753193
06:32:25 INFO - Bytes received: 18753193
06:32:25 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')]}
06:32:25 INFO - trying https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2
06:32:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
06:32:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
06:32:26 INFO - Downloaded 62707594 bytes.
06:32:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2
06:32:26 INFO - mkdir: /builds/slave/test/properties
06:32:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
06:32:26 INFO - Writing to file /builds/slave/test/properties/build_url
06:32:26 INFO - Contents:
06:32:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2
06:32:26 INFO - Running post-action listener: _resource_record_post_action
06:32:26 INFO - Running post-action listener: find_tests_for_verification
06:32:26 INFO - Running post-action listener: set_extra_try_arguments
06:32:26 INFO - [mozharness: 2017-12-04 14:32:26.838578Z] Finished download-and-extract step (success)
06:32:26 INFO - [mozharness: 2017-12-04 14:32:26.838672Z] Running populate-webroot step.
06:32:26 INFO - Running pre-action listener: _resource_record_pre_action
06:32:26 INFO - Running main action method: populate_webroot
06:32:26 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
06:32:26 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['tresize',
06:32:26 INFO - 'tcanvasmark']},
06:32:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
06:32:26 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['dromaeo_css',
06:32:26 INFO - 'kraken']},
06:32:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
06:32:26 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
06:32:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'tests': ['damp', 'tps']},
06:32:26 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['damp', 'tps']},
06:32:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
06:32:26 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['dromaeo_dom']},
06:32:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
06:32:26 INFO - 'glvideo',
06:32:26 INFO - 'displaylist_mutate']},
06:32:26 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['basic_compositor_video',
06:32:26 INFO - 'glvideo']},
06:32:26 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
06:32:26 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['ts_paint_webext',
06:32:26 INFO - 'tp5o_webext']},
06:32:26 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
06:32:26 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
06:32:26 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
06:32:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
06:32:26 INFO - 'talos_options': ['--mitmproxy',
06:32:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
06:32:26 INFO - '--firstNonBlankPaint'],
06:32:26 INFO - 'tests': ['tp6_google_heavy',
06:32:26 INFO - 'tp6_youtube_heavy',
06:32:26 INFO - 'tp6_amazon_heavy',
06:32:26 INFO - 'tp6_facebook_heavy']},
06:32:26 INFO - 'other-e10s': {'tests': ['a11yr',
06:32:26 INFO - 'ts_paint',
06:32:26 INFO - 'tpaint',
06:32:26 INFO - 'sessionrestore',
06:32:26 INFO - 'sessionrestore_many_windows',
06:32:26 INFO - 'sessionrestore_no_auto_restore',
06:32:26 INFO - 'tabpaint',
06:32:26 INFO - 'cpstartup']},
06:32:26 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['a11yr',
06:32:26 INFO - 'ts_paint',
06:32:26 INFO - 'tpaint',
06:32:26 INFO - 'sessionrestore',
06:32:26 INFO - 'sessionrestore_many_windows',
06:32:26 INFO - 'sessionrestore_no_auto_restore',
06:32:26 INFO - 'tabpaint',
06:32:26 INFO - 'cpstartup']},
06:32:26 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
06:32:26 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
06:32:26 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['perf_reftest_singletons']},
06:32:26 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['perf_reftest']},
06:32:26 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
06:32:26 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['speedometer']},
06:32:26 INFO - 'svgr-e10s': {'tests': ['tsvgx',
06:32:26 INFO - 'tsvgr_opacity',
06:32:26 INFO - 'tart',
06:32:26 INFO - 'tscrollx',
06:32:26 INFO - 'tsvg_static']},
06:32:26 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['tsvgx',
06:32:26 INFO - 'tsvgr_opacity',
06:32:26 INFO - 'tart',
06:32:26 INFO - 'tscrollx',
06:32:26 INFO - 'tsvg_static']},
06:32:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
06:32:26 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'talos_options': ['--disable-stylo'],
06:32:26 INFO - 'tests': ['tp5o']},
06:32:26 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
06:32:26 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
06:32:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
06:32:26 INFO - 'talos_options': ['--mitmproxy',
06:32:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
06:32:26 INFO - '--firstNonBlankPaint'],
06:32:26 INFO - 'tests': ['tp6_google',
06:32:26 INFO - 'tp6_youtube',
06:32:26 INFO - 'tp6_amazon',
06:32:26 INFO - 'tp6_facebook']},
06:32:26 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
06:32:26 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
06:32:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
06:32:26 INFO - 'talos_options': ['--disable-stylo',
06:32:26 INFO - '--mitmproxy',
06:32:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
06:32:26 INFO - '--firstNonBlankPaint'],
06:32:26 INFO - 'tests': ['tp6_google',
06:32:26 INFO - 'tp6_youtube',
06:32:26 INFO - 'tp6_amazon',
06:32:26 INFO - 'tp6_facebook']},
06:32:26 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
06:32:26 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
06:32:26 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
06:32:26 INFO - 'talos_options': ['--stylo-threads=1',
06:32:26 INFO - '--mitmproxy',
06:32:26 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
06:32:26 INFO - '--firstNonBlankPaint'],
06:32:26 INFO - 'tests': ['tp6_google',
06:32:26 INFO - 'tp6_youtube',
06:32:26 INFO - 'tp6_amazon',
06:32:26 INFO - 'tp6_facebook']},
06:32:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'talos_options': ['--xperf_path',
06:32:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
06:32:26 INFO - 'tests': ['tp5n']},
06:32:26 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
06:32:26 INFO - 'talos_options': ['--disable-stylo',
06:32:26 INFO - '--xperf_path',
06:32:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
06:32:26 INFO - 'tests': ['tp5n']}}}
06:32:26 INFO - Running post-action listener: _resource_record_post_action
06:32:26 INFO - [mozharness: 2017-12-04 14:32:26.857139Z] Finished populate-webroot step (success)
06:32:26 INFO - [mozharness: 2017-12-04 14:32:26.857232Z] Running create-virtualenv step.
06:32:26 INFO - Running pre-action listener: _resource_record_pre_action
06:32:26 INFO - Running main action method: create_virtualenv
06:32:26 INFO - Creating virtualenv /builds/slave/test/build/venv
06:32:26 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
06:32:26 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
06:32:26 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
06:32:28 INFO - Using real prefix '/usr'
06:32:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python
06:32:30 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
06:32:32 INFO - Installing setuptools, pip, wheel...done.
06:32:32 INFO - Return code: 0
06:32:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
06:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
06:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
06:32:32 INFO - Using env: {'DISPLAY': ':0',
06:32:32 INFO - 'HOME': '/home/cltbld',
06:32:32 INFO - 'LANG': 'en_US.UTF-8',
06:32:32 INFO - 'LANGUAGE': 'en_US:en',
06:32:32 INFO - 'LOGNAME': 'cltbld',
06:32:32 INFO - 'MAIL': '/var/mail/cltbld',
06:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:32 INFO - 'MOZ_NO_REMOTE': '1',
06:32:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:32 INFO - 'NO_EM_RESTART': '1',
06:32:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:32 INFO - 'PWD': '/builds/slave/test',
06:32:32 INFO - 'SHELL': '/bin/bash',
06:32:32 INFO - 'SHLVL': '1',
06:32:32 INFO - 'TERM': 'linux',
06:32:32 INFO - 'TMOUT': '86400',
06:32:32 INFO - 'USER': 'cltbld',
06:32:32 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:32 INFO - '_': '/tools/buildbot/bin/python'}
06:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
06:32:32 INFO - Return code: 0
06:32:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
06:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
06:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
06:32:32 INFO - Using env: {'DISPLAY': ':0',
06:32:32 INFO - 'HOME': '/home/cltbld',
06:32:32 INFO - 'LANG': 'en_US.UTF-8',
06:32:32 INFO - 'LANGUAGE': 'en_US:en',
06:32:32 INFO - 'LOGNAME': 'cltbld',
06:32:32 INFO - 'MAIL': '/var/mail/cltbld',
06:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:32 INFO - 'MOZ_NO_REMOTE': '1',
06:32:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:32 INFO - 'NO_EM_RESTART': '1',
06:32:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:32 INFO - 'PWD': '/builds/slave/test',
06:32:32 INFO - 'SHELL': '/bin/bash',
06:32:32 INFO - 'SHLVL': '1',
06:32:32 INFO - 'TERM': 'linux',
06:32:32 INFO - 'TMOUT': '86400',
06:32:32 INFO - 'USER': 'cltbld',
06:32:32 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:32 INFO - '_': '/tools/buildbot/bin/python'}
06:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:32 INFO - Collecting psutil>=3.1.1
06:32:34 INFO - Installing collected packages: psutil
06:32:34 INFO - Successfully installed psutil-3.1.1
06:32:34 INFO - Return code: 0
06:32:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
06:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
06:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
06:32:34 INFO - Using env: {'DISPLAY': ':0',
06:32:34 INFO - 'HOME': '/home/cltbld',
06:32:34 INFO - 'LANG': 'en_US.UTF-8',
06:32:34 INFO - 'LANGUAGE': 'en_US:en',
06:32:34 INFO - 'LOGNAME': 'cltbld',
06:32:34 INFO - 'MAIL': '/var/mail/cltbld',
06:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:34 INFO - 'MOZ_NO_REMOTE': '1',
06:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:34 INFO - 'NO_EM_RESTART': '1',
06:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:34 INFO - 'PWD': '/builds/slave/test',
06:32:34 INFO - 'SHELL': '/bin/bash',
06:32:34 INFO - 'SHLVL': '1',
06:32:34 INFO - 'TERM': 'linux',
06:32:34 INFO - 'TMOUT': '86400',
06:32:34 INFO - 'USER': 'cltbld',
06:32:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:34 INFO - '_': '/tools/buildbot/bin/python'}
06:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:34 INFO - Collecting mozsystemmonitor==0.3
06:32:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
06:32:35 INFO - Installing collected packages: mozsystemmonitor
06:32:35 INFO - Successfully installed mozsystemmonitor-0.3
06:32:35 INFO - Return code: 0
06:32:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
06:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
06:32:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
06:32:35 INFO - Using env: {'DISPLAY': ':0',
06:32:35 INFO - 'HOME': '/home/cltbld',
06:32:35 INFO - 'LANG': 'en_US.UTF-8',
06:32:35 INFO - 'LANGUAGE': 'en_US:en',
06:32:35 INFO - 'LOGNAME': 'cltbld',
06:32:35 INFO - 'MAIL': '/var/mail/cltbld',
06:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:35 INFO - 'MOZ_NO_REMOTE': '1',
06:32:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:35 INFO - 'NO_EM_RESTART': '1',
06:32:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:35 INFO - 'PWD': '/builds/slave/test',
06:32:35 INFO - 'SHELL': '/bin/bash',
06:32:35 INFO - 'SHLVL': '1',
06:32:35 INFO - 'TERM': 'linux',
06:32:35 INFO - 'TMOUT': '86400',
06:32:35 INFO - 'USER': 'cltbld',
06:32:35 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:35 INFO - '_': '/tools/buildbot/bin/python'}
06:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:36 INFO - Collecting jsonschema==2.5.1
06:32:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
06:32:37 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
06:32:38 INFO - Installing collected packages: functools32, jsonschema
06:32:38 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
06:32:38 INFO - Return code: 0
06:32:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
06:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
06:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
06:32:38 INFO - Using env: {'DISPLAY': ':0',
06:32:38 INFO - 'HOME': '/home/cltbld',
06:32:38 INFO - 'LANG': 'en_US.UTF-8',
06:32:38 INFO - 'LANGUAGE': 'en_US:en',
06:32:38 INFO - 'LOGNAME': 'cltbld',
06:32:38 INFO - 'MAIL': '/var/mail/cltbld',
06:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:38 INFO - 'MOZ_NO_REMOTE': '1',
06:32:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:38 INFO - 'NO_EM_RESTART': '1',
06:32:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:38 INFO - 'PWD': '/builds/slave/test',
06:32:38 INFO - 'SHELL': '/bin/bash',
06:32:38 INFO - 'SHLVL': '1',
06:32:38 INFO - 'TERM': 'linux',
06:32:38 INFO - 'TMOUT': '86400',
06:32:38 INFO - 'USER': 'cltbld',
06:32:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:38 INFO - '_': '/tools/buildbot/bin/python'}
06:32:38 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
06:32:38 INFO - Return code: 0
06:32:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
06:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:32:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
06:32:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
06:32:38 INFO - Using env: {'DISPLAY': ':0',
06:32:38 INFO - 'HOME': '/home/cltbld',
06:32:38 INFO - 'LANG': 'en_US.UTF-8',
06:32:38 INFO - 'LANGUAGE': 'en_US:en',
06:32:38 INFO - 'LOGNAME': 'cltbld',
06:32:38 INFO - 'MAIL': '/var/mail/cltbld',
06:32:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:38 INFO - 'MOZ_NO_REMOTE': '1',
06:32:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:38 INFO - 'NO_EM_RESTART': '1',
06:32:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:38 INFO - 'PWD': '/builds/slave/test',
06:32:38 INFO - 'SHELL': '/bin/bash',
06:32:38 INFO - 'SHLVL': '1',
06:32:38 INFO - 'TERM': 'linux',
06:32:38 INFO - 'TMOUT': '86400',
06:32:38 INFO - 'USER': 'cltbld',
06:32:38 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:38 INFO - '_': '/tools/buildbot/bin/python'}
06:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:39 INFO - Collecting blobuploader==1.2.4
06:32:40 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
06:32:44 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
06:32:46 INFO - Installing collected packages: requests, docopt, blobuploader
06:32:46 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
06:32:46 INFO - Return code: 0
06:32:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv
06:32:46 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')]}
06:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:46 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')]}
06:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:46 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 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:46 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
06:32:46 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
06:32:46 INFO - Using env: {'DISPLAY': ':0',
06:32:46 INFO - 'HOME': '/home/cltbld',
06:32:46 INFO - 'LANG': 'en_US.UTF-8',
06:32:46 INFO - 'LANGUAGE': 'en_US:en',
06:32:46 INFO - 'LOGNAME': 'cltbld',
06:32:46 INFO - 'MAIL': '/var/mail/cltbld',
06:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:46 INFO - 'MOZ_NO_REMOTE': '1',
06:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:46 INFO - 'NO_EM_RESTART': '1',
06:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:46 INFO - 'PWD': '/builds/slave/test',
06:32:46 INFO - 'SHELL': '/bin/bash',
06:32:46 INFO - 'SHLVL': '1',
06:32:46 INFO - 'TERM': 'linux',
06:32:46 INFO - 'TMOUT': '86400',
06:32:46 INFO - 'USER': 'cltbld',
06:32:46 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:46 INFO - '_': '/tools/buildbot/bin/python'}
06:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:46 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
06:32:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
06:32:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
06:32:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
06:32:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
06:32:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
06:32:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
06:32:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
06:32:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
06:32:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
06:32:48 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
06:32:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
06:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
06:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
06:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
06:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
06:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
06:32:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
06:32:50 INFO - Running setup.py install for manifestparser: started
06:32:50 INFO - Running setup.py install for manifestparser: finished with status 'done'
06:32:50 INFO - Running setup.py install for mozcrash: started
06:32:50 INFO - Running setup.py install for mozcrash: finished with status 'done'
06:32:50 INFO - Running setup.py install for mozdebug: started
06:32:50 INFO - Running setup.py install for mozdebug: finished with status 'done'
06:32:50 INFO - Running setup.py install for mozdevice: started
06:32:50 INFO - Running setup.py install for mozdevice: finished with status 'done'
06:32:50 INFO - Running setup.py install for mozfile: started
06:32:51 INFO - Running setup.py install for mozfile: finished with status 'done'
06:32:51 INFO - Running setup.py install for mozhttpd: started
06:32:51 INFO - Running setup.py install for mozhttpd: finished with status 'done'
06:32:51 INFO - Running setup.py install for mozinfo: started
06:32:51 INFO - Running setup.py install for mozinfo: finished with status 'done'
06:32:51 INFO - Running setup.py install for mozInstall: started
06:32:51 INFO - Running setup.py install for mozInstall: finished with status 'done'
06:32:51 INFO - Running setup.py install for mozleak: started
06:32:51 INFO - Running setup.py install for mozleak: finished with status 'done'
06:32:51 INFO - Running setup.py install for mozlog: started
06:32:52 INFO - Running setup.py install for mozlog: finished with status 'done'
06:32:52 INFO - Running setup.py install for moznetwork: started
06:32:52 INFO - Running setup.py install for moznetwork: finished with status 'done'
06:32:52 INFO - Running setup.py install for mozprocess: started
06:32:52 INFO - Running setup.py install for mozprocess: finished with status 'done'
06:32:52 INFO - Running setup.py install for mozprofile: started
06:32:52 INFO - Running setup.py install for mozprofile: finished with status 'done'
06:32:52 INFO - Running setup.py install for mozrunner: started
06:32:53 INFO - Running setup.py install for mozrunner: finished with status 'done'
06:32:53 INFO - Running setup.py install for mozscreenshot: started
06:32:53 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
06:32:53 INFO - Running setup.py install for moztest: started
06:32:53 INFO - Running setup.py install for moztest: finished with status 'done'
06:32:53 INFO - Running setup.py install for mozversion: started
06:32:53 INFO - Running setup.py install for mozversion: finished with status 'done'
06:32:54 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.14 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
06:32:54 INFO - Return code: 0
06:32:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv
06:32:54 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')]}
06:32:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:32:54 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')]}
06:32:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:32:54 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 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:32:54 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
06:32:54 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
06:32:54 INFO - Using env: {'DISPLAY': ':0',
06:32:54 INFO - 'HOME': '/home/cltbld',
06:32:54 INFO - 'LANG': 'en_US.UTF-8',
06:32:54 INFO - 'LANGUAGE': 'en_US:en',
06:32:54 INFO - 'LOGNAME': 'cltbld',
06:32:54 INFO - 'MAIL': '/var/mail/cltbld',
06:32:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:32:54 INFO - 'MOZ_NO_REMOTE': '1',
06:32:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:32:54 INFO - 'NO_EM_RESTART': '1',
06:32:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:32:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:32:54 INFO - 'PWD': '/builds/slave/test',
06:32:54 INFO - 'SHELL': '/bin/bash',
06:32:54 INFO - 'SHLVL': '1',
06:32:54 INFO - 'TERM': 'linux',
06:32:54 INFO - 'TMOUT': '86400',
06:32:54 INFO - 'USER': 'cltbld',
06:32:54 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:32:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:32:54 INFO - '_': '/tools/buildbot/bin/python'}
06:32:54 INFO - Ignoring indexes: https://pypi.python.org/simple
06:32:54 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
06:32:54 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))
06:32:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
06:32:54 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))
06:32:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
06:32:55 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))
06:32:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
06:32:55 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))
06:32:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
06:32:55 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))
06:32:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
06:32:55 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))
06:32:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
06:32:55 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))
06:32:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
06:32:56 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))
06:32:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
06:32:56 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))
06:32:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
06:32:56 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))
06:32:56 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
06:32:56 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))
06:32:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
06:32:56 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))
06:32:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
06:32:57 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))
06:32:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
06:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.14 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))
06:32:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
06:32:57 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))
06:32:57 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
06:32:57 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))
06:32:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
06:32:58 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))
06:32:58 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
06:32:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
06:32:59 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))
06:32:59 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))
06:32:59 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))
06:32:59 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))
06:32:59 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))
06:32:59 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))
06:32:59 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
06:33:00 INFO - Installing collected packages: six, blessings
06:33:00 INFO - Successfully installed blessings-1.6 six-1.10.0
06:33:00 INFO - Return code: 0
06:33:00 INFO - Done creating virtualenv /builds/slave/test/build/venv.
06:33:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
06:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
06:33:00 INFO - Reading from file tmpfile_stdout
06:33:00 INFO - Reading from file tmpfile_stderr
06:33:00 INFO - Current package versions:
06:33:00 INFO - blessings == 1.6
06:33:00 INFO - blobuploader == 1.2.4
06:33:00 INFO - docopt == 0.6.1
06:33:00 INFO - functools32 == 3.2.3.post2
06:33:00 INFO - jsonschema == 2.5.1
06:33:00 INFO - manifestparser == 1.2
06:33:00 INFO - mozInstall == 1.14
06:33:00 INFO - mozcrash == 1.0
06:33:00 INFO - mozdebug == 0.1
06:33:00 INFO - mozdevice == 0.51
06:33:00 INFO - mozfile == 1.2
06:33:00 INFO - mozhttpd == 0.7
06:33:00 INFO - mozinfo == 0.10
06:33:00 INFO - mozleak == 0.1
06:33:00 INFO - mozlog == 3.5
06:33:00 INFO - moznetwork == 0.27
06:33:00 INFO - mozprocess == 0.25
06:33:00 INFO - mozprofile == 0.29
06:33:00 INFO - mozrunner == 6.14
06:33:00 INFO - mozscreenshot == 0.1
06:33:00 INFO - mozsystemmonitor == 0.3
06:33:00 INFO - moztest == 0.8
06:33:00 INFO - mozversion == 1.4
06:33:00 INFO - psutil == 3.1.1
06:33:00 INFO - requests == 1.2.3
06:33:00 INFO - six == 1.10.0
06:33:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv
06:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:33:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:33:00 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 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:33:00 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
06:33:00 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
06:33:00 INFO - Using env: {'DISPLAY': ':0',
06:33:00 INFO - 'HOME': '/home/cltbld',
06:33:00 INFO - 'LANG': 'en_US.UTF-8',
06:33:00 INFO - 'LANGUAGE': 'en_US:en',
06:33:00 INFO - 'LOGNAME': 'cltbld',
06:33:00 INFO - 'MAIL': '/var/mail/cltbld',
06:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:33:00 INFO - 'MOZ_NO_REMOTE': '1',
06:33:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:33:00 INFO - 'NO_EM_RESTART': '1',
06:33:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:33:00 INFO - 'PWD': '/builds/slave/test',
06:33:00 INFO - 'SHELL': '/bin/bash',
06:33:00 INFO - 'SHLVL': '1',
06:33:00 INFO - 'TERM': 'linux',
06:33:00 INFO - 'TMOUT': '86400',
06:33:00 INFO - 'USER': 'cltbld',
06:33:00 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:33:00 INFO - '_': '/tools/buildbot/bin/python'}
06:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple
06:33:01 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))
06:33:01 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))
06:33:01 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))
06:33:01 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))
06:33:01 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))
06:33:01 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))
06:33:01 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))
06:33:01 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))
06:33:01 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))
06:33:01 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
06:33:02 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
06:33:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
06:33:03 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))
06:33:03 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))
06:33:03 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))
06:33:03 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))
06:33:04 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
06:33:04 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
06:33:05 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
06:33:05 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
06:33:06 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
06:33:06 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
06:33:07 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
06:33:07 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
06:33:07 INFO - Found existing installation: requests 1.2.3
06:33:07 INFO - Uninstalling requests-1.2.3:
06:33:07 INFO - Successfully uninstalled requests-1.2.3
06:33:08 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
06:33:08 INFO - Return code: 0
06:33:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
06:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:33:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
06:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
06:33:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
06:33:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--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 0x164de30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b2990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17d99a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, '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': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101', '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
06:33:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
06:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
06:33:08 INFO - Using env: {'DISPLAY': ':0',
06:33:08 INFO - 'HOME': '/home/cltbld',
06:33:08 INFO - 'LANG': 'en_US.UTF-8',
06:33:08 INFO - 'LANGUAGE': 'en_US:en',
06:33:08 INFO - 'LOGNAME': 'cltbld',
06:33:08 INFO - 'MAIL': '/var/mail/cltbld',
06:33:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:33:08 INFO - 'MOZ_NO_REMOTE': '1',
06:33:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:33:08 INFO - 'NO_EM_RESTART': '1',
06:33:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:33:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:33:08 INFO - 'PWD': '/builds/slave/test',
06:33:08 INFO - 'SHELL': '/bin/bash',
06:33:08 INFO - 'SHLVL': '1',
06:33:08 INFO - 'TERM': 'linux',
06:33:08 INFO - 'TMOUT': '86400',
06:33:08 INFO - 'USER': 'cltbld',
06:33:08 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:33:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:33:08 INFO - '_': '/tools/buildbot/bin/python'}
06:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple
06:33:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
06:33:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
06:33:08 INFO - Return code: 0
06:33:08 INFO - Running post-action listener: _resource_record_post_action
06:33:08 INFO - Running post-action listener: _start_resource_monitoring
06:33:08 INFO - Starting resource monitoring.
06:33:08 INFO - [mozharness: 2017-12-04 14:33:08.668294Z] Finished create-virtualenv step (success)
06:33:08 INFO - [mozharness: 2017-12-04 14:33:08.668741Z] Running install step.
06:33:08 INFO - Running pre-action listener: _resource_record_pre_action
06:33:08 INFO - Running main action method: install
06:33:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
06:33:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
06:33:09 INFO - Reading from file tmpfile_stdout
06:33:09 INFO - Reading from file tmpfile_stderr
06:33:09 INFO - Detecting whether we're running mozinstall >=1.0...
06:33:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
06:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
06:33:09 INFO - Reading from file tmpfile_stdout
06:33:09 INFO - Output received:
06:33:09 INFO - Usage: mozinstall [options] installer
06:33:09 INFO - Options:
06:33:09 INFO - -h, --help show this help message and exit
06:33:09 INFO - -d DEST, --destination=DEST
06:33:09 INFO - Directory to install application into. [default:
06:33:09 INFO - "/builds/slave/test"]
06:33:09 INFO - --app=APP Application being installed. [default: firefox]
06:33:09 INFO - mkdir: /builds/slave/test/build/application
06:33:09 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']
06:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
06:33:27 INFO - Reading from file tmpfile_stdout
06:33:27 INFO - Output received:
06:33:27 INFO - /builds/slave/test/build/application/firefox/firefox
06:33:27 INFO - Running post-action listener: _resource_record_post_action
06:33:27 INFO - [mozharness: 2017-12-04 14:33:27.703989Z] Finished install step (success)
06:33:27 INFO - [mozharness: 2017-12-04 14:33:27.704231Z] Running setup-mitmproxy step.
06:33:27 INFO - Running pre-action listener: _resource_record_pre_action
06:33:27 INFO - Running main action method: setup_mitmproxy
06:33:27 INFO - Skipping: mitmproxy is not required
06:33:27 INFO - Running post-action listener: _resource_record_post_action
06:33:27 INFO - [mozharness: 2017-12-04 14:33:27.705479Z] Finished setup-mitmproxy step (success)
06:33:27 INFO - [mozharness: 2017-12-04 14:33:27.705697Z] Running run-tests step.
06:33:27 INFO - Running pre-action listener: _resource_record_pre_action
06:33:27 INFO - Running pre-action listener: _set_gcov_prefix
06:33:27 INFO - Running main action method: run_tests
06:33:27 WARNING - Try message not found.
06:33:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
06:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
06:33:27 INFO - Python 2.7.3
06:33:27 INFO - Return code: 0
06:33:27 INFO - grabbing minidump binary from tooltool
06:33:27 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')]}
06:33:27 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 0x17d6b40>, '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 0x17d7a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17d7ed0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
06:33:27 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
06:33:27 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
06:33:27 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
06:33:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
06:33:27 INFO - Return code: 0
06:33:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
06:33:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
06:33:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
06:33:27 INFO - ENV: RUST_BACKTRACE is now full
06:33:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
06:33:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
06:33:27 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/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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
06:33:27 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/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-053 --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
06:33:27 INFO - Using env: {'DISPLAY': ':0',
06:33:27 INFO - 'HOME': '/home/cltbld',
06:33:27 INFO - 'LANG': 'en_US.UTF-8',
06:33:27 INFO - 'LANGUAGE': 'en_US:en',
06:33:27 INFO - 'LOGNAME': 'cltbld',
06:33:27 INFO - 'MAIL': '/var/mail/cltbld',
06:33:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
06:33:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
06:33:27 INFO - 'MOZ_ACCELERATED': '1',
06:33:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:33:27 INFO - 'MOZ_NO_REMOTE': '1',
06:33:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
06:33:27 INFO - 'MOZ_WEBRENDER': '1',
06:33:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:33:27 INFO - 'NO_EM_RESTART': '1',
06:33:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:33:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:33:27 INFO - 'PWD': '/builds/slave/test',
06:33:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
06:33:27 INFO - 'RUST_BACKTRACE': 'full',
06:33:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
06:33:27 INFO - 'SHELL': '/bin/bash',
06:33:27 INFO - 'SHLVL': '1',
06:33:27 INFO - 'TERM': 'linux',
06:33:27 INFO - 'TMOUT': '86400',
06:33:27 INFO - 'USER': 'cltbld',
06:33:27 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:33:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:33:27 INFO - '_': '/tools/buildbot/bin/python'}
06:33:27 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/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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
06:33:28 INFO - mozversion application_buildid: 20171204133538
06:33:28 INFO - mozversion application_changeset: 195bb467e6cb5c8c5f5fb2858c0a55b2d0b9552d
06:33:28 INFO - mozversion application_display_name: Nightly
06:33:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
06:33:28 INFO - mozversion application_name: Firefox
06:33:28 INFO - mozversion application_remotingname: firefox
06:33:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
06:33:28 INFO - mozversion application_vendor: Mozilla
06:33:28 INFO - mozversion application_version: 59.0a1
06:33:28 INFO - mozversion platform_buildid: 20171204133538
06:33:28 INFO - mozversion platform_changeset: 195bb467e6cb5c8c5f5fb2858c0a55b2d0b9552d
06:33:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
06:33:28 INFO - mozversion platform_version: 59.0a1
06:33:28 INFO - using testdate: 1512398008
06:33:28 INFO - actual date: 1512398008
06:33:28 INFO - starting webserver on 'localhost:50661'
06:33:28 INFO - SUITE-START | Running 3 tests
06:33:28 INFO - TEST-START | basic_compositor_video
06:33:28 INFO - Initialising browser for basic_compositor_video test...
06:33:28 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
06:33:28 INFO - Installing Add-ons
06:33:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50661/getInfo.html -profile /tmp/tmpM6kSR0/profile
06:33:28 INFO - TEST-INFO | started process 3771 (/builds/slave/test/build/application/firefox/firefox http://localhost:50661/getInfo.html)
06:33:43 INFO - TEST-INFO | 3771: exit 0
06:33:43 INFO - Browser initialized.
06:33:43 INFO - Running cycle 1/1 for basic_compositor_video test...
06:33:43 INFO - Using env: {'DISPLAY': ':0',
06:33:43 INFO - 'HOME': '/home/cltbld',
06:33:43 INFO - 'JSGC_DISABLE_POISONING': '1',
06:33:43 INFO - 'LANG': 'en_US.UTF-8',
06:33:43 INFO - 'LANGUAGE': 'en_US:en',
06:33:43 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
06:33:43 INFO - 'LOGNAME': 'cltbld',
06:33:43 INFO - 'MAIL': '/var/mail/cltbld',
06:33:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
06:33:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
06:33:43 INFO - 'MOZ_ACCELERATED': '1',
06:33:43 INFO - 'MOZ_CRASHREPORTER': '1',
06:33:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:33:43 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
06:33:43 INFO - 'MOZ_NO_REMOTE': '1',
06:33:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
06:33:43 INFO - 'MOZ_WEBRENDER': '1',
06:33:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:33:43 INFO - 'NO_EM_RESTART': '1',
06:33:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:33:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:33:43 INFO - 'PWD': '/builds/slave/test',
06:33:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
06:33:43 INFO - 'RUST_BACKTRACE': 'full',
06:33:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
06:33:43 INFO - 'SHELL': '/bin/bash',
06:33:43 INFO - 'SHLVL': '1',
06:33:43 INFO - 'TERM': 'linux',
06:33:43 INFO - 'TMOUT': '86400',
06:33:43 INFO - 'USER': 'cltbld',
06:33:43 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:33:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:33:43 INFO - '_': '/tools/buildbot/bin/python'}
06:33:43 INFO - TEST-INFO | started process 4110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM6kSR0/profile)
06:33:44 INFO - PID 4110 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:33:44 INFO - PID 4110 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:33:44 INFO - PID 4110 |
06:33:44 INFO - PID 4110 | (/builds/slave/test/build/application/firefox/firefox:4171): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
06:33:44 INFO - PID 4110 |
06:33:46 INFO - PID 4110 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:34:30 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.478232865400496 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.440103658536586 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4900539419087138 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4751794554455455 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0651072522982634 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.044398782343988 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3860383747178315 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3345416666666705 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.5635035629453697 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.5096491228070175 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.532794561933539 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.496415730337076 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.867082379862694 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.690066889632113 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.89781609195403 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.645647840531574 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.929526558891457 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.6888795986622025 ms/frame
06:34:30 INFO - PID 4110 |
06:34:30 INFO - PID 4110 | Cycle 1(1): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:35:16 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.459340163934426 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4372716199756397 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4961272878535774 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4820223325062027 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0551832993890007 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0445281582952832 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3791722972972993 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3348583333333286 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.542567886658796 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.509114035087719 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.52638009049774 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.496348314606742 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.865560640732266 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.667016666666678 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.88150229357798 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.6905183946488425 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.91453917050692 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.691321070234095 ms/frame
06:35:16 INFO - PID 4110 |
06:35:16 INFO - PID 4110 | Cycle 1(2): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:36:02 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4552536824877254 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4392317073170737 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.492367109634551 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4816439205955345 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0646833503575066 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0540229007633592 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3711011235955044 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3341083333333335 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.5674791914387636 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.515246045694199 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.552761760242787 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.504932432432438 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.865846681922196 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.691939799331101 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.882293577981636 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.6924581939799515 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.865343249427932 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.669883333333321 ms/frame
06:36:02 INFO - PID 4110 |
06:36:02 INFO - PID 4110 | Cycle 1(3): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:36:48 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.461201804757999 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4365956151035317 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4904149377593368 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4819602977667494 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0559470468431775 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0503887195121955 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.37070786516854 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3294592346089806 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.5468971631205664 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.5092807017543874 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.525897435897433 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.495303370786525 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.897298850574706 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.66850000000001 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.834555808656026 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.691872909699005 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.881594036697248 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.668366666666674 ms/frame
06:36:48 INFO - PID 4110 |
06:36:48 INFO - PID 4110 | Cycle 1(4): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:37:34 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.461431501230517 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.436485992691839 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.498309741881765 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4849503105590065 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0739497950819663 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.053908396946564 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3680751964085296 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3341166666666684 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.5549526066350725 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.497604895104897 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.518335843373493 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.495022471910112 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.850605022831055 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.666933333333339 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.93026558891455 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.6895986622073655 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.883474770642201 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.669699999999987 ms/frame
06:37:34 INFO - PID 4110 |
06:37:34 INFO - PID 4110 | Cycle 1(5): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:38:20 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4477243066884173 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4402317073170736 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4817866004962776 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4761819306930692 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0676738241308787 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.04890243902439 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3641199551569487 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.333825000000003 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.5554680094786715 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.5028721541155856 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.538524962178512 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.504831081081082 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.976732558139524 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.667750000000015 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.865926773455369 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.668066666666661 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.865125858123564 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.6914882943143965 ms/frame
06:38:20 INFO - PID 4110 |
06:38:20 INFO - PID 4110 | Cycle 1(6): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:39:06 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4418958502847845 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.439536585365853 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4960399334442593 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4728615574783697 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.051958290946084 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0535877862595426 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3676262626262607 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.334566666666669 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.555296208530808 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.5091491228070177 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.512774436090224 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.494471910112361 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.865308924485133 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.6460963455149455 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.929041570438803 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.690167224080245 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.881238532110094 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.667800000000013 ms/frame
06:39:06 INFO - PID 4110 |
06:39:06 INFO - PID 4110 | Cycle 1(7): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:39:52 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.447079934747145 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4364799025578563 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4882504145936983 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4787670384138782 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0521108850457788 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.053671755725192 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3709719101123605 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.334841666666665 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.555130331753553 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.509596491228072 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.526372549019603 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.504763513513518 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.851232876712338 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.6905016722408 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.85156392694064 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.647574750830559 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.850205479452053 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.645299003322261 ms/frame
06:39:52 INFO - PID 4110 |
06:39:52 INFO - PID 4110 | Cycle 1(8): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:40:38 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.459561475409836 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4370280146163212 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.482059553349876 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.481954094292803 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0651634320735446 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.039582066869301 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3678170594837264 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3341499999999966 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.555053317535545 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.5042294220665515 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.512142857142858 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.506058558558555 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.882855504587162 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.668149999999999 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.865263157894726 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.64988372093025 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.881594036697248 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.66743333333332 ms/frame
06:40:38 INFO - PID 4110 |
06:40:38 INFO - PID 4110 | Cycle 1(9): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:41:24 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.457805077805078 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4335036496350373 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.489734439834025 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4789900867410153 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0681748466257663 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.054763358778627 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.374808773903262 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3334583333333345 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.525981198589895 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.5089736842105226 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.533028700906342 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.485571748878929 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.882603211009163 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.668833333333338 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.86660183066362 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.670166666666652 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.8814334862385325 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.667433333333344 ms/frame
06:41:24 INFO - PID 4110 |
06:41:24 INFO - PID 4110 | Cycle 1(10): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:42:10 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.457800982800983 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4432844932844935 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5001833333333336 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4813957816377172 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.058924566768604 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0538549618320605 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.356532438478749 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3390734557595985 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.5503846153846164 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.515237258347976 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.545424242424242 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.484730941704033 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.881456422018346 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.669866666666688 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.849965753424649 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.667666666666676 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.912592165898606 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.669650000000013 ms/frame
06:42:10 INFO - PID 4110 |
06:42:10 INFO - PID 4110 | Cycle 1(11): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:42:56 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_startup = 2.455756955810147 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.440103658536585 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.496110648918469 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4784386617100376 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_startup = 3.058634046890928 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.058348623853211 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_startup = 3.3709719101123583 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1_inclip = 3.333650000000004 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_startup = 3.546211583924347 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_1.1_inclip = 3.508771929824561 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_startup = 4.51945030120482 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.480p.60fps.webm_scale_2_inclip = 4.494561797752813 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.898068965517241 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.669933333333344 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.884048165137614 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.692107023411379 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.865549199084658 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.689096989966558 ms/frame
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | Cycle 1(12): loaded http://localhost:50661/tests/video/video_playback.html (next: http://localhost:50661/tests/video/video_playback.html)
06:42:56 INFO - PID 4110 | __start_tp_report
06:42:56 INFO - PID 4110 | _x_x_mozilla_page_load
06:42:56 INFO - PID 4110 | _x_x_mozilla_page_load_details
06:42:56 INFO - PID 4110 | |i|pagename|runs|
06:42:56 INFO - PID 4110 | |0;240p.120fps.mp4_scale_1_startup;2.478232865400496;2.459340163934426;2.4552536824877254;2.461201804757999;2.461431501230517;2.4477243066884173;2.4418958502847845;2.447079934747145;2.459561475409836;2.457805077805078;2.457800982800983;2.455756955810147
06:42:56 INFO - PID 4110 | |1;240p.120fps.mp4_scale_1_inclip;2.440103658536586;2.4372716199756397;2.4392317073170737;2.4365956151035317;2.436485992691839;2.4402317073170736;2.439536585365853;2.4364799025578563;2.4370280146163212;2.4335036496350373;2.4432844932844935;2.440103658536585
06:42:56 INFO - PID 4110 | |2;240p.120fps.mp4_scale_1.1_startup;2.4900539419087138;2.4961272878535774;2.492367109634551;2.4904149377593368;2.498309741881765;2.4817866004962776;2.4960399334442593;2.4882504145936983;2.482059553349876;2.489734439834025;2.5001833333333336;2.496110648918469
06:42:56 INFO - PID 4110 | |3;240p.120fps.mp4_scale_1.1_inclip;2.4751794554455455;2.4820223325062027;2.4816439205955345;2.4819602977667494;2.4849503105590065;2.4761819306930692;2.4728615574783697;2.4787670384138782;2.481954094292803;2.4789900867410153;2.4813957816377172;2.4784386617100376
06:42:56 INFO - PID 4110 | |4;240p.120fps.mp4_scale_2_startup;3.0651072522982634;3.0551832993890007;3.0646833503575066;3.0559470468431775;3.0739497950819663;3.0676738241308787;3.051958290946084;3.0521108850457788;3.0651634320735446;3.0681748466257663;3.058924566768604;3.058634046890928
06:42:56 INFO - PID 4110 | |5;240p.120fps.mp4_scale_2_inclip;3.044398782343988;3.0445281582952832;3.0540229007633592;3.0503887195121955;3.053908396946564;3.04890243902439;3.0535877862595426;3.053671755725192;3.039582066869301;3.054763358778627;3.0538549618320605;3.058348623853211
06:42:56 INFO - PID 4110 | |6;480p.60fps.webm_scale_1_startup;3.3860383747178315;3.3791722972972993;3.3711011235955044;3.37070786516854;3.3680751964085296;3.3641199551569487;3.3676262626262607;3.3709719101123605;3.3678170594837264;3.374808773903262;3.356532438478749;3.3709719101123583
06:42:56 INFO - PID 4110 | |7;480p.60fps.webm_scale_1_inclip;3.3345416666666705;3.3348583333333286;3.3341083333333335;3.3294592346089806;3.3341166666666684;3.333825000000003;3.334566666666669;3.334841666666665;3.3341499999999966;3.3334583333333345;3.3390734557595985;3.333650000000004
06:42:56 INFO - PID 4110 | |8;480p.60fps.webm_scale_1.1_startup;3.5635035629453697;3.542567886658796;3.5674791914387636;3.5468971631205664;3.5549526066350725;3.5554680094786715;3.555296208530808;3.555130331753553;3.555053317535545;3.525981198589895;3.5503846153846164;3.546211583924347
06:42:56 INFO - PID 4110 | |9;480p.60fps.webm_scale_1.1_inclip;3.5096491228070175;3.509114035087719;3.515246045694199;3.5092807017543874;3.497604895104897;3.5028721541155856;3.5091491228070177;3.509596491228072;3.5042294220665515;3.5089736842105226;3.515237258347976;3.508771929824561
06:42:56 INFO - PID 4110 | |10;480p.60fps.webm_scale_2_startup;4.532794561933539;4.52638009049774;4.552761760242787;4.525897435897433;4.518335843373493;4.538524962178512;4.512774436090224;4.526372549019603;4.512142857142858;4.533028700906342;4.545424242424242;4.51945030120482
06:42:56 INFO - PID 4110 | |11;480p.60fps.webm_scale_2_inclip;4.496415730337076;4.496348314606742;4.504932432432438;4.495303370786525;4.495022471910112;4.504831081081082;4.494471910112361;4.504763513513518;4.506058558558555;4.485571748878929;4.484730941704033;4.494561797752813
06:42:56 INFO - PID 4110 | |12;1080p.60fps.mp4_scale_1_startup;6.867082379862694;6.865560640732266;6.865846681922196;6.897298850574706;6.850605022831055;6.976732558139524;6.865308924485133;6.851232876712338;6.882855504587162;6.882603211009163;6.881456422018346;6.898068965517241
06:42:56 INFO - PID 4110 | |13;1080p.60fps.mp4_scale_1_inclip;6.690066889632113;6.667016666666678;6.691939799331101;6.66850000000001;6.666933333333339;6.667750000000015;6.6460963455149455;6.6905016722408;6.668149999999999;6.668833333333338;6.669866666666688;6.669933333333344
06:42:56 INFO - PID 4110 | |14;1080p.60fps.mp4_scale_1.1_startup;6.89781609195403;6.88150229357798;6.882293577981636;6.834555808656026;6.93026558891455;6.865926773455369;6.929041570438803;6.85156392694064;6.865263157894726;6.86660183066362;6.849965753424649;6.884048165137614
06:42:56 INFO - PID 4110 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.645647840531574;6.6905183946488425;6.6924581939799515;6.691872909699005;6.6895986622073655;6.668066666666661;6.690167224080245;6.647574750830559;6.64988372093025;6.670166666666652;6.667666666666676;6.692107023411379
06:42:56 INFO - PID 4110 | |16;1080p.60fps.mp4_scale_2_startup;6.929526558891457;6.91453917050692;6.865343249427932;6.881594036697248;6.883474770642201;6.865125858123564;6.881238532110094;6.850205479452053;6.881594036697248;6.8814334862385325;6.912592165898606;6.865549199084658
06:42:56 INFO - PID 4110 | |17;1080p.60fps.mp4_scale_2_inclip;6.6888795986622025;6.691321070234095;6.669883333333321;6.668366666666674;6.669699999999987;6.6914882943143965;6.667800000000013;6.645299003322261;6.66743333333332;6.667433333333344;6.669650000000013;6.689096989966558
06:42:56 INFO - PID 4110 | __end_tp_report
06:42:56 INFO - PID 4110 | __start_cc_report
06:42:56 INFO - PID 4110 | _x_x_mozilla_cycle_collect,617
06:42:56 INFO - PID 4110 | __end_cc_report
06:42:56 INFO - PID 4110 | __startTimestamp1512398576578__endTimestamp
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | ------- Summary: start -------
06:42:56 INFO - PID 4110 | Number of tests: 18
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.46 Median:2.46 stddev:0.01 (0.4%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 2.5 2.5 2.5 2.5 2.5 2.4 2.4 2.4 2.5 2.5 2.5 2.5
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.44 Median:2.44 stddev:0.00 (0.1%) stddev-sans-first:0.00
06:42:56 INFO - PID 4110 | Values: 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.49 Median:2.49 stddev:0.01 (0.2%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | 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
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.48 Median:2.48 stddev:0.00 (0.1%) stddev-sans-first:0.00
06:42:56 INFO - PID 4110 | 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
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.06 Median:3.06 stddev:0.01 (0.2%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | 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
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.05 Median:3.05 stddev:0.01 (0.2%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 3.0 3.0 3.1 3.1 3.1 3.0 3.1 3.1 3.0 3.1 3.1 3.1
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.37 Median:3.37 stddev:0.01 (0.2%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 3.4 3.4 3.4 3.4 3.4 3.4 3.4 3.4 3.4 3.4 3.4 3.4
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.33 Median:3.33 stddev:0.00 (0.1%) stddev-sans-first:0.00
06:42:56 INFO - PID 4110 | Values: 3.3 3.3 3.3 3.3 3.3 3.3 3.3 3.3 3.3 3.3 3.3 3.3
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:3.55 Median:3.56 stddev:0.01 (0.3%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 3.6 3.5 3.6 3.5 3.6 3.6 3.6 3.6 3.6 3.5 3.6 3.5
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:3.51 Median:3.51 stddev:0.00 (0.1%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 3.5 3.5 3.5 3.5 3.5 3.5 3.5 3.5 3.5 3.5 3.5 3.5
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.53 Median:4.53 stddev:0.01 (0.3%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 4.5 4.5 4.6 4.5 4.5 4.5 4.5 4.5 4.5 4.5 4.5 4.5
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.50 Median:4.50 stddev:0.01 (0.2%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 4.5 4.5 4.5 4.5 4.5 4.5 4.5 4.5 4.5 4.5 4.5 4.5
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:6.88 Median:6.88 stddev:0.03 (0.5%) stddev-sans-first:0.03
06:42:56 INFO - PID 4110 | Values: 6.9 6.9 6.9 6.9 6.9 7.0 6.9 6.9 6.9 6.9 6.9 6.9
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.67 Median:6.67 stddev:0.01 (0.2%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 6.7 6.7 6.7 6.7 6.7 6.7 6.6 6.7 6.7 6.7 6.7 6.7
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:6.88 Median:6.88 stddev:0.03 (0.4%) stddev-sans-first:0.03
06:42:56 INFO - PID 4110 | Values: 6.9 6.9 6.9 6.8 6.9 6.9 6.9 6.9 6.9 6.9 6.8 6.9
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.67 Median:6.69 stddev:0.02 (0.3%) stddev-sans-first:0.02
06:42:56 INFO - PID 4110 | Values: 6.6 6.7 6.7 6.7 6.7 6.7 6.7 6.6 6.6 6.7 6.7 6.7
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:6.88 Median:6.88 stddev:0.02 (0.3%) stddev-sans-first:0.02
06:42:56 INFO - PID 4110 | Values: 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9
06:42:56 INFO - PID 4110 |
06:42:56 INFO - PID 4110 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.67 Median:6.67 stddev:0.01 (0.2%) stddev-sans-first:0.01
06:42:56 INFO - PID 4110 | Values: 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.6 6.7 6.7 6.7 6.7
06:42:56 INFO - PID 4110 | -------- Summary: end --------
06:42:56 INFO - PID 4110 |
06:43:02 INFO - TEST-INFO | 4110: exit 0
06:43:02 INFO - TEST-OK | basic_compositor_video | took 574162ms
06:43:02 INFO - TEST-START | glvideo
06:43:02 INFO - Initialising browser for glvideo test...
06:43:02 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
06:43:02 INFO - Installing Add-ons
06:43:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50661/getInfo.html -profile /tmp/tmpx7RCXi/profile
06:43:02 INFO - TEST-INFO | started process 5397 (/builds/slave/test/build/application/firefox/firefox http://localhost:50661/getInfo.html)
06:43:13 INFO - TEST-INFO | 5397: exit 0
06:43:13 INFO - Browser initialized.
06:43:13 INFO - Running cycle 1/1 for glvideo test...
06:43:13 INFO - Using env: {'DISPLAY': ':0',
06:43:13 INFO - 'HOME': '/home/cltbld',
06:43:13 INFO - 'JSGC_DISABLE_POISONING': '1',
06:43:13 INFO - 'LANG': 'en_US.UTF-8',
06:43:13 INFO - 'LANGUAGE': 'en_US:en',
06:43:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
06:43:13 INFO - 'LOGNAME': 'cltbld',
06:43:13 INFO - 'MAIL': '/var/mail/cltbld',
06:43:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
06:43:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
06:43:13 INFO - 'MOZ_ACCELERATED': '1',
06:43:13 INFO - 'MOZ_CRASHREPORTER': '1',
06:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:43:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
06:43:13 INFO - 'MOZ_NO_REMOTE': '1',
06:43:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
06:43:13 INFO - 'MOZ_WEBRENDER': '1',
06:43:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:43:13 INFO - 'NO_EM_RESTART': '1',
06:43:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:43:13 INFO - 'PWD': '/builds/slave/test',
06:43:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
06:43:13 INFO - 'RUST_BACKTRACE': 'full',
06:43:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
06:43:13 INFO - 'SHELL': '/bin/bash',
06:43:13 INFO - 'SHLVL': '1',
06:43:13 INFO - 'TERM': 'linux',
06:43:13 INFO - 'TMOUT': '86400',
06:43:13 INFO - 'USER': 'cltbld',
06:43:13 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:43:13 INFO - '_': '/tools/buildbot/bin/python'}
06:43:13 INFO - TEST-INFO | started process 5696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx7RCXi/profile)
06:43:13 INFO - PID 5696 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:43:14 INFO - PID 5696 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:43:14 INFO - PID 5696 |
06:43:14 INFO - PID 5696 | (/builds/slave/test/build/application/firefox/firefox:5757): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
06:43:14 INFO - PID 5696 |
06:43:15 INFO - PID 5696 | [talos glvideo result] Mean tick time across 100 ticks: 6.86615 ms
06:43:15 INFO - PID 5696 | Cycle 1(1): loaded http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html)
06:43:16 INFO - PID 5696 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:43:17 INFO - PID 5696 | [talos glvideo result] Mean tick time across 100 ticks: 6.52345 ms
06:43:17 INFO - PID 5696 | Cycle 1(2): loaded http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html)
06:43:18 INFO - PID 5696 | [talos glvideo result] Mean tick time across 100 ticks: 6.694399999999999 ms
06:43:18 INFO - PID 5696 | Cycle 1(3): loaded http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html)
06:43:19 INFO - PID 5696 | [talos glvideo result] Mean tick time across 100 ticks: 6.597899999999999 ms
06:43:19 INFO - PID 5696 | Cycle 1(4): loaded http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html)
06:43:21 INFO - PID 5696 | [talos glvideo result] Mean tick time across 100 ticks: 6.669250000000001 ms
06:43:21 INFO - PID 5696 | Cycle 1(5): loaded http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50661/tests/webgl/benchmarks/video/video_upload.html)
06:43:21 INFO - PID 5696 | __start_tp_report
06:43:21 INFO - PID 5696 | _x_x_mozilla_page_load
06:43:21 INFO - PID 5696 | _x_x_mozilla_page_load_details
06:43:21 INFO - PID 5696 | |i|pagename|runs|
06:43:21 INFO - PID 5696 | |0;Mean tick time across 100 ticks: ;6.86615;6.52345;6.694399999999999;6.597899999999999;6.669250000000001
06:43:21 INFO - PID 5696 | __end_tp_report
06:43:21 INFO - PID 5696 | __start_cc_report
06:43:21 INFO - PID 5696 | _x_x_mozilla_cycle_collect,291
06:43:21 INFO - PID 5696 | __end_cc_report
06:43:21 INFO - PID 5696 | __startTimestamp1512398601389__endTimestamp
06:43:21 INFO - PID 5696 |
06:43:21 INFO - PID 5696 | ------- Summary: start -------
06:43:21 INFO - PID 5696 | Number of tests: 1
06:43:21 INFO - PID 5696 |
06:43:21 INFO - PID 5696 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.67 Median:6.67 stddev:0.13 (1.9%) stddev-sans-first:0.08
06:43:21 INFO - PID 5696 | Values: 6.9 6.5 6.7 6.6 6.7
06:43:21 INFO - PID 5696 | -------- Summary: end --------
06:43:21 INFO - PID 5696 |
06:43:22 INFO - PID 5696 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
06:43:27 INFO - TEST-INFO | 5696: exit 0
06:43:27 INFO - TEST-OK | glvideo | took 25053ms
06:43:27 INFO - TEST-START | displaylist_mutate
06:43:27 INFO - Initialising browser for displaylist_mutate test...
06:43:27 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
06:43:27 INFO - Installing Add-ons
06:43:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50661/getInfo.html -profile /tmp/tmpkNDJHP/profile
06:43:27 INFO - TEST-INFO | started process 5937 (/builds/slave/test/build/application/firefox/firefox http://localhost:50661/getInfo.html)
06:43:39 INFO - TEST-INFO | 5937: exit 0
06:43:39 INFO - Browser initialized.
06:43:39 INFO - Running cycle 1/1 for displaylist_mutate test...
06:43:39 INFO - Using env: {'DISPLAY': ':0',
06:43:39 INFO - 'HOME': '/home/cltbld',
06:43:39 INFO - 'JSGC_DISABLE_POISONING': '1',
06:43:39 INFO - 'LANG': 'en_US.UTF-8',
06:43:39 INFO - 'LANGUAGE': 'en_US:en',
06:43:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
06:43:39 INFO - 'LOGNAME': 'cltbld',
06:43:39 INFO - 'MAIL': '/var/mail/cltbld',
06:43:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
06:43:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
06:43:39 INFO - 'MOZ_ACCELERATED': '1',
06:43:39 INFO - 'MOZ_CRASHREPORTER': '1',
06:43:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
06:43:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
06:43:39 INFO - 'MOZ_NO_REMOTE': '1',
06:43:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
06:43:39 INFO - 'MOZ_WEBRENDER': '1',
06:43:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
06:43:39 INFO - 'NO_EM_RESTART': '1',
06:43:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
06:43:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
06:43:39 INFO - 'PWD': '/builds/slave/test',
06:43:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
06:43:39 INFO - 'RUST_BACKTRACE': 'full',
06:43:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
06:43:39 INFO - 'SHELL': '/bin/bash',
06:43:39 INFO - 'SHLVL': '1',
06:43:39 INFO - 'TERM': 'linux',
06:43:39 INFO - 'TMOUT': '86400',
06:43:39 INFO - 'USER': 'cltbld',
06:43:39 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101',
06:43:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
06:43:39 INFO - '_': '/tools/buildbot/bin/python'}
06:43:39 INFO - TEST-INFO | started process 6229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkNDJHP/profile)
06:43:40 INFO - PID 6229 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:43:40 INFO - PID 6229 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:43:40 INFO - PID 6229 |
06:43:40 INFO - PID 6229 | (/builds/slave/test/build/application/firefox/firefox:6290): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
06:43:40 INFO - PID 6229 |
06:43:46 INFO - PID 6229 | Cycle 1(1): loaded http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html)
06:43:46 INFO - PID 6229 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
06:43:52 INFO - PID 6229 | Cycle 1(2): loaded http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html)
06:43:58 INFO - PID 6229 | Cycle 1(3): loaded http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html)
06:44:05 INFO - PID 6229 | Cycle 1(4): loaded http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html)
06:44:11 INFO - PID 6229 | Cycle 1(5): loaded http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50661/tests/layout/benchmarks/displaylist_mutate.html)
06:44:11 INFO - PID 6229 | __start_tp_report
06:44:11 INFO - PID 6229 | _x_x_mozilla_page_load
06:44:11 INFO - PID 6229 | _x_x_mozilla_page_load_details
06:44:11 INFO - PID 6229 | |i|pagename|runs|
06:44:11 INFO - PID 6229 | |0;/displaylist_mutate.html;5345.3150000000005;5371.305;5346.58;5503.474999999999;5446.905
06:44:11 INFO - PID 6229 | __end_tp_report
06:44:11 INFO - PID 6229 | __start_cc_report
06:44:11 INFO - PID 6229 | _x_x_mozilla_cycle_collect,242
06:44:11 INFO - PID 6229 | __end_cc_report
06:44:11 INFO - PID 6229 | __startTimestamp1512398651618__endTimestamp
06:44:11 INFO - PID 6229 |
06:44:11 INFO - PID 6229 | ------- Summary: start -------
06:44:11 INFO - PID 6229 | Number of tests: 1
06:44:11 INFO - PID 6229 |
06:44:11 INFO - PID 6229 | [#0] /displaylist_mutate.html Cycles:5 Average:5402.72 Median:5371.31 stddev:69.89 (1.3%) stddev-sans-first:71.69
06:44:11 INFO - PID 6229 | Values: 5345.3 5371.3 5346.6 5503.5 5446.9
06:44:11 INFO - PID 6229 | -------- Summary: end --------
06:44:11 INFO - PID 6229 |
06:44:12 INFO - PID 6229 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
06:44:17 INFO - TEST-INFO | 6229: exit 0
06:44:17 INFO - TEST-OK | displaylist_mutate | took 49969ms
06:44:17 INFO - SUITE-END | took 649s
06:44:17 INFO - Completed test suite (00:10:49)
06:44:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.137146084745905, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.478232865400496, 2.459340163934426, 2.4552536824877254, 2.461201804757999, 2.461431501230517, 2.4477243066884173, 2.4418958502847845, 2.447079934747145, 2.459561475409836, 2.457805077805078, 2.457800982800983, 2.455756955810147], "value": 2.457800982800983, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.440103658536586, 2.4372716199756397, 2.4392317073170737, 2.4365956151035317, 2.436485992691839, 2.4402317073170736, 2.439536585365853, 2.4364799025578563, 2.4370280146163212, 2.4335036496350373, 2.4432844932844935, 2.440103658536585], "value": 2.4372716199756397, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4900539419087138, 2.4961272878535774, 2.492367109634551, 2.4904149377593368, 2.498309741881765, 2.4817866004962776, 2.4960399334442593, 2.4882504145936983, 2.482059553349876, 2.489734439834025, 2.5001833333333336, 2.496110648918469], "value": 2.492367109634551, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4751794554455455, 2.4820223325062027, 2.4816439205955345, 2.4819602977667494, 2.4849503105590065, 2.4761819306930692, 2.4728615574783697, 2.4787670384138782, 2.481954094292803, 2.4789900867410153, 2.4813957816377172, 2.4784386617100376], "value": 2.4813957816377172, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0651072522982634, 3.0551832993890007, 3.0646833503575066, 3.0559470468431775, 3.0739497950819663, 3.0676738241308787, 3.051958290946084, 3.0521108850457788, 3.0651634320735446, 3.0681748466257663, 3.058924566768604, 3.058634046890928], "value": 3.058924566768604, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.044398782343988, 3.0445281582952832, 3.0540229007633592, 3.0503887195121955, 3.053908396946564, 3.04890243902439, 3.0535877862595426, 3.053671755725192, 3.039582066869301, 3.054763358778627, 3.0538549618320605, 3.058348623853211], "value": 3.053671755725192, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.3860383747178315, 3.3791722972972993, 3.3711011235955044, 3.37070786516854, 3.3680751964085296, 3.3641199551569487, 3.3676262626262607, 3.3709719101123605, 3.3678170594837264, 3.374808773903262, 3.356532438478749, 3.3709719101123583], "value": 3.37070786516854, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.3345416666666705, 3.3348583333333286, 3.3341083333333335, 3.3294592346089806, 3.3341166666666684, 3.333825000000003, 3.334566666666669, 3.334841666666665, 3.3341499999999966, 3.3334583333333345, 3.3390734557595985, 3.333650000000004], "value": 3.3341166666666684, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5635035629453697, 3.542567886658796, 3.5674791914387636, 3.5468971631205664, 3.5549526066350725, 3.5554680094786715, 3.555296208530808, 3.555130331753553, 3.555053317535545, 3.525981198589895, 3.5503846153846164, 3.546211583924347], "value": 3.5549526066350725, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5096491228070175, 3.509114035087719, 3.515246045694199, 3.5092807017543874, 3.497604895104897, 3.5028721541155856, 3.5091491228070177, 3.509596491228072, 3.5042294220665515, 3.5089736842105226, 3.515237258347976, 3.508771929824561], "value": 3.509114035087719, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.532794561933539, 4.52638009049774, 4.552761760242787, 4.525897435897433, 4.518335843373493, 4.538524962178512, 4.512774436090224, 4.526372549019603, 4.512142857142858, 4.533028700906342, 4.545424242424242, 4.51945030120482], "value": 4.526372549019603, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.496415730337076, 4.496348314606742, 4.504932432432438, 4.495303370786525, 4.495022471910112, 4.504831081081082, 4.494471910112361, 4.504763513513518, 4.506058558558555, 4.485571748878929, 4.484730941704033, 4.494561797752813], "value": 4.495303370786525, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.867082379862694, 6.865560640732266, 6.865846681922196, 6.897298850574706, 6.850605022831055, 6.976732558139524, 6.865308924485133, 6.851232876712338, 6.882855504587162, 6.882603211009163, 6.881456422018346, 6.898068965517241], "value": 6.881456422018346, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.690066889632113, 6.667016666666678, 6.691939799331101, 6.66850000000001, 6.666933333333339, 6.667750000000015, 6.6460963455149455, 6.6905016722408, 6.668149999999999, 6.668833333333338, 6.669866666666688, 6.669933333333344], "value": 6.66850000000001, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.89781609195403, 6.88150229357798, 6.882293577981636, 6.834555808656026, 6.93026558891455, 6.865926773455369, 6.929041570438803, 6.85156392694064, 6.865263157894726, 6.86660183066362, 6.849965753424649, 6.884048165137614], "value": 6.86660183066362, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.645647840531574, 6.6905183946488425, 6.6924581939799515, 6.691872909699005, 6.6895986622073655, 6.668066666666661, 6.690167224080245, 6.647574750830559, 6.64988372093025, 6.670166666666652, 6.667666666666676, 6.692107023411379], "value": 6.6895986622073655, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.929526558891457, 6.91453917050692, 6.865343249427932, 6.881594036697248, 6.883474770642201, 6.865125858123564, 6.881238532110094, 6.850205479452053, 6.881594036697248, 6.8814334862385325, 6.912592165898606, 6.865549199084658], "value": 6.8814334862385325, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.6888795986622025, 6.691321070234095, 6.669883333333321, 6.668366666666674, 6.669699999999987, 6.6914882943143965, 6.667800000000013, 6.645299003322261, 6.66743333333332, 6.667433333333344, 6.669650000000013, 6.689096989966558], "value": 6.669650000000013, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.86615, 6.52345, 6.694399999999999, 6.597899999999999, 6.669250000000001], "value": 6.633575, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5345.3150000000005, 5371.305, 5346.58, 5503.474999999999, 5446.905], "value": 5409.105, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
06:44:17 INFO - Return code: 0
06:44:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
06:44:17 INFO - # TBPL SUCCESS #
06:44:17 INFO - Running post-action listener: _package_coverage_data
06:44:17 INFO - Running post-action listener: _resource_record_post_action
06:44:17 INFO - [mozharness: 2017-12-04 14:44:17.898999Z] Finished run-tests step (success)
06:44:17 INFO - Running post-run listener: _resource_record_post_run
06:44:17 INFO - Total resource usage - Wall time: 669s; CPU: 41.0%; Read bytes: 18952192; Write bytes: 349483008; Read time: 4588; Write time: 377448
06:44:17 INFO - TinderboxPrint: CPU usage
41.3%
06:44:17 INFO - TinderboxPrint: I/O read bytes / time
18,952,192 / 4,588
06:44:17 INFO - TinderboxPrint: I/O write bytes / time
349,483,008 / 377,448
06:44:17 INFO - TinderboxPrint: CPU idle
3,066.2 (58.5%)
06:44:17 INFO - TinderboxPrint: CPU system
566.8 (10.8%)
06:44:17 INFO - TinderboxPrint: CPU user
1,578.1 (30.1%)
06:44:17 INFO - TinderboxPrint: Swap in / out
0 / 0
06:44:17 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 86728704; Read time: 0; Write time: 83836
06:44:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
06:44:17 INFO - run-tests - Wall time: 650s; CPU: 42.0%; Read bytes: 16560128; Write bytes: 262057984; Read time: 4068; Write time: 293524
06:44:18 INFO - Running post-run listener: _upload_blobber_files
06:44:18 INFO - Blob upload gear active.
06:44:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
06:44:18 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
06:44:18 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']
06:44:18 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
06:44:18 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
06:44:18 INFO - SNIMissingWarning
06:44:18 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
06:44:18 INFO - InsecurePlatformWarning
06:44:18 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.)
06:44:18 INFO - SubjectAltNameWarning
06:44:18 INFO - (blobuploader) - INFO - Open directory for files ...
06:44:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
06:44:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:44:18 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:44:18 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
06:44:18 INFO - InsecurePlatformWarning
06:44:18 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.)
06:44:18 INFO - SubjectAltNameWarning
06:44:19 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
06:44:19 INFO - InsecurePlatformWarning
06:44:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
06:44:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:44:19 INFO - (blobuploader) - INFO - Done attempting.
06:44:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
06:44:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:44:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:44:19 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
06:44:19 INFO - InsecurePlatformWarning
06:44:19 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.)
06:44:19 INFO - SubjectAltNameWarning
06:44:20 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
06:44:20 INFO - InsecurePlatformWarning
06:44:20 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
06:44:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:44:20 INFO - (blobuploader) - INFO - Done attempting.
06:44:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
06:44:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:44:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:44:20 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
06:44:20 INFO - InsecurePlatformWarning
06:44:20 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.)
06:44:20 INFO - SubjectAltNameWarning
06:44:24 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
06:44:24 INFO - InsecurePlatformWarning
06:44:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
06:44:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:44:25 INFO - (blobuploader) - INFO - Done attempting.
06:44:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
06:44:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
06:44:25 INFO - (blobuploader) - INFO - Uploading, attempt #1.
06:44:25 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
06:44:25 INFO - InsecurePlatformWarning
06:44:25 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.)
06:44:25 INFO - SubjectAltNameWarning
06:44:25 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
06:44:25 INFO - InsecurePlatformWarning
06:44:26 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
06:44:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
06:44:26 INFO - (blobuploader) - INFO - Done attempting.
06:44:26 INFO - (blobuploader) - INFO - Iteration through files over.
06:44:26 INFO - Return code: 0
06:44:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
06:44:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
06:44:26 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8bb7495fe675c19a5cf31a51b4bd7b8a93fba8da9896a0f7a3d6e4a085a843faf084a11fc0546f6b1aa469f197fc910c89c231d46299ad0e6fdc2d33360d667", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ca4c9f57fc953ab7799f6f4844f88700a3112437669fa6b05676d67ab173269057376c9ba6832f2db71c6b76786d715ad1dd3111d2adc4371df32f15f1353c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/659bffa577e994d36280a4c85f8b646d6ffd27ede08ac9359d1e149b7085dc69ff8c16754dbd2b43b9c238259bc95cddfab7a030794d7a4f02979bde72092b0c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/034de937a52bea073620f7537879aeccb6143915818e7610ef136297dce40589d9e7009919a1722f4c4bfe320afb160d448281b1d7fddd1d6270cfe959381899"}
06:44:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
06:44:26 INFO - Writing to file /builds/slave/test/properties/blobber_files
06:44:26 INFO - Contents:
06:44:26 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8bb7495fe675c19a5cf31a51b4bd7b8a93fba8da9896a0f7a3d6e4a085a843faf084a11fc0546f6b1aa469f197fc910c89c231d46299ad0e6fdc2d33360d667", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ca4c9f57fc953ab7799f6f4844f88700a3112437669fa6b05676d67ab173269057376c9ba6832f2db71c6b76786d715ad1dd3111d2adc4371df32f15f1353c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/659bffa577e994d36280a4c85f8b646d6ffd27ede08ac9359d1e149b7085dc69ff8c16754dbd2b43b9c238259bc95cddfab7a030794d7a4f02979bde72092b0c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/034de937a52bea073620f7537879aeccb6143915818e7610ef136297dce40589d9e7009919a1722f4c4bfe320afb160d448281b1d7fddd1d6270cfe959381899"}
06:44:26 INFO - Running post-run listener: copy_logs_to_upload_dir
06:44:26 INFO - Copying logs to upload dir...
06:44:26 INFO - mkdir: /builds/slave/test/build/upload/logs
06:44:26 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=736.981506
========= master_lag: -0.00 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 16 secs) (at 2017-12-04 06:44:26.106411) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-04 06:44:26.110456) =========
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=920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8bb7495fe675c19a5cf31a51b4bd7b8a93fba8da9896a0f7a3d6e4a085a843faf084a11fc0546f6b1aa469f197fc910c89c231d46299ad0e6fdc2d33360d667", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ca4c9f57fc953ab7799f6f4844f88700a3112437669fa6b05676d67ab173269057376c9ba6832f2db71c6b76786d715ad1dd3111d2adc4371df32f15f1353c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/659bffa577e994d36280a4c85f8b646d6ffd27ede08ac9359d1e149b7085dc69ff8c16754dbd2b43b9c238259bc95cddfab7a030794d7a4f02979bde72092b0c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/034de937a52bea073620f7537879aeccb6143915818e7610ef136297dce40589d9e7009919a1722f4c4bfe320afb160d448281b1d7fddd1d6270cfe959381899"}
build_url:https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011214
build_url: 'https://queue.taskcluster.net/v1/task/Jt3P76azQ6KJOSt0y_qSJQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d8bb7495fe675c19a5cf31a51b4bd7b8a93fba8da9896a0f7a3d6e4a085a843faf084a11fc0546f6b1aa469f197fc910c89c231d46299ad0e6fdc2d33360d667", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55ca4c9f57fc953ab7799f6f4844f88700a3112437669fa6b05676d67ab173269057376c9ba6832f2db71c6b76786d715ad1dd3111d2adc4371df32f15f1353c", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/659bffa577e994d36280a4c85f8b646d6ffd27ede08ac9359d1e149b7085dc69ff8c16754dbd2b43b9c238259bc95cddfab7a030794d7a4f02979bde72092b0c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/034de937a52bea073620f7537879aeccb6143915818e7610ef136297dce40589d9e7009919a1722f4c4bfe320afb160d448281b1d7fddd1d6270cfe959381899"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-04 06:44:26.142640) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 06:44:26.143313) =========
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=920470bf432669ad7e3a5387000001ca-1512397925.469236-1902937101
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004499
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-04 06:44:26.225951) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-04 06:44:26.226260) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-04 06:44:26.226569) =========
========= Total master_lag: 0.17 =========