builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-031
starttime: 1511311408.01
results: success (0)
revision: b96f009478987d44a68a8d7cad40c6a3d6626235
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.012682) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.013413) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.013891) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.029381) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.029977) =========
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=db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-21 16:43:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 19.4M=0.001s
2017-11-21 16:43:28 (19.4 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.178425
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.225731) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.226292) =========
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=db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020499
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:28.277709) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-21 16:43:28.278043) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b96f009478987d44a68a8d7cad40c6a3d6626235 --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 b96f009478987d44a68a8d7cad40c6a3d6626235 --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=db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-21 16:43:28,328 truncating revision to first 12 chars
2017-11-21 16:43:28,328 Setting DEBUG logging.
2017-11-21 16:43:28,328 attempt 1/10
2017-11-21 16:43:28,328 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b96f00947898?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-21 16:43:28,592 unpacking tar archive at: mozilla-central-b96f00947898/testing/mozharness/
program finished with exit code 0
elapsedTime=1.026431
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-21 16:43:29.322479) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:29.322829) =========
script_repo_revision: b96f009478987d44a68a8d7cad40c6a3d6626235
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:29.323197) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:29.323476) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-21 16:43:29.338751) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 39 secs) (at 2017-11-21 16:43:29.339172) =========
/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=db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
16:43:29 INFO - MultiFileLogger online at 20171121 16:43:29 in /builds/slave/test
16:43:29 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
16:43:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
16:43:29 INFO - {'append_to_log': False,
16:43:29 INFO - 'base_work_dir': '/builds/slave/test',
16:43:29 INFO - 'blob_upload_branch': 'Firefox',
16:43:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
16:43:29 INFO - 'branch': 'Firefox',
16:43:29 INFO - 'buildbot_json_path': 'buildprops.json',
16:43:29 INFO - 'code_coverage': False,
16:43:29 INFO - 'config_files': ('talos/linux_config.py',),
16:43:29 INFO - 'default_actions': ('clobber',
16:43:29 INFO - 'read-buildbot-config',
16:43:29 INFO - 'download-and-extract',
16:43:29 INFO - 'populate-webroot',
16:43:29 INFO - 'create-virtualenv',
16:43:29 INFO - 'install',
16:43:29 INFO - 'setup-mitmproxy',
16:43:29 INFO - 'run-tests'),
16:43:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
16:43:29 INFO - 'disable_ccov_upload': False,
16:43:29 INFO - 'disable_stylo': False,
16:43:29 INFO - 'download_minidump_stackwalk': True,
16:43:29 INFO - 'download_symbols': 'ondemand',
16:43:29 INFO - 'e10s': False,
16:43:29 INFO - 'enable_stylo': False,
16:43:29 INFO - 'enable_webrender': False,
16:43:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
16:43:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
16:43:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
16:43:29 INFO - 'gecko_profile': False,
16:43:29 INFO - 'gecko_profile_interval': 0,
16:43:29 INFO - 'installer_path': 'installer.exe',
16:43:29 INFO - 'jsd_code_coverage': False,
16:43:29 INFO - 'log_level': 'info',
16:43:29 INFO - 'log_name': 'talos',
16:43:29 INFO - 'log_to_console': True,
16:43:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
16:43:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
16:43:29 INFO - 'opt_config_files': (),
16:43:29 INFO - 'pip_index': False,
16:43:29 INFO - 'suite': 'g4-e10s',
16:43:29 INFO - 'system_bits': '32',
16:43:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
16:43:29 INFO - 'title': 'talos-linux64-ix-031',
16:43:29 INFO - 'tooltool_cache': '/builds/tooltool_cache',
16:43:29 INFO - 'use_talos_json': True,
16:43:29 INFO - 'verify': 'False',
16:43:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
16:43:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:43:29 INFO - 'work_dir': 'build'}
16:43:29 INFO - [mozharness: 2017-11-22 00:43:29.522661Z] Running clobber step.
16:43:29 INFO - Running pre-action listener: _resource_record_pre_action
16:43:29 INFO - Running main action method: clobber
16:43:29 INFO - rmtree: /builds/slave/test/build
16:43:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
16:43:30 INFO - Running post-action listener: _resource_record_post_action
16:43:30 INFO - [mozharness: 2017-11-22 00:43:30.657945Z] Finished clobber step (success)
16:43:30 INFO - [mozharness: 2017-11-22 00:43:30.658040Z] Running read-buildbot-config step.
16:43:30 INFO - Running pre-action listener: _resource_record_pre_action
16:43:30 INFO - Running main action method: read_buildbot_config
16:43:30 INFO - Using buildbot properties:
16:43:30 INFO - {
16:43:30 INFO - "project": "",
16:43:30 INFO - "product": "firefox",
16:43:30 INFO - "who": "ccoroiu@mozilla.com",
16:43:30 INFO - "installer_path": "public/build/target.tar.bz2",
16:43:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
16:43:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
16:43:30 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
16:43:30 INFO - "stage_platform": "linux64-qr",
16:43:30 INFO - "basedir": "/builds/slave/test",
16:43:30 INFO - "buildnumber": 210,
16:43:30 INFO - "platform": "ubuntu64_hw_qr",
16:43:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
16:43:30 INFO - "slavebuilddir": "test",
16:43:30 INFO - "taskId": "OM4zSOz_SPi2FLtueLEF8A",
16:43:30 INFO - "branch": "mozilla-central",
16:43:30 INFO - "script_repo_revision": "production",
16:43:30 INFO - "revision": "b96f009478987d44a68a8d7cad40c6a3d6626235",
16:43:30 INFO - "slavename": "talos-linux64-ix-031",
16:43:30 INFO - "repo_path": "mozilla-central"
16:43:30 INFO - }
16:43:30 INFO - Finding installer, test and symbols from parent task.
16:43:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OM4zSOz_SPi2FLtueLEF8A'}, attempt #1
16:43:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OM4zSOz_SPi2FLtueLEF8A'}, attempt #1
16:43:31 INFO - Task dependencies: P2MZIH96Qi-wy-ag3pkitw
16:43:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw'}, attempt #1
16:43:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2
16:43:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.test_packages.json
16:43:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.crashreporter-symbols.zip
16:43:32 INFO - Running post-action listener: _resource_record_post_action
16:43:32 INFO - [mozharness: 2017-11-22 00:43:32.016587Z] Finished read-buildbot-config step (success)
16:43:32 INFO - [mozharness: 2017-11-22 00:43:32.016802Z] Running download-and-extract step.
16:43:32 INFO - Running pre-action listener: _resource_record_pre_action
16:43:32 INFO - Running main action method: download_and_extract
16:43:32 INFO - mkdir: /builds/slave/test/build/tests
16:43: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')]}
16:43:32 INFO - trying https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.test_packages.json
16:43:32 INFO - Downloading https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
16:43:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
16:43:32 INFO - Downloaded 989 bytes.
16:43:32 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
16:43:32 INFO - Using the following test package requirements:
16:43:32 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
16:43:32 INFO - u'common': [u'target.common.tests.zip'],
16:43:32 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
16:43:32 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
16:43:32 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
16:43:32 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
16:43:32 INFO - u'mozbase': [u'target.common.tests.zip'],
16:43:32 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
16:43:32 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
16:43:32 INFO - u'web-platform': [u'target.common.tests.zip',
16:43:32 INFO - u'target.web-platform.tests.tar.gz'],
16:43:32 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
16:43:32 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
16:43:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.common.tests.zip
16:43:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.common.tests.zip'}, attempt #1
16:43:32 INFO - Fetch https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.common.tests.zip into memory
16:43:33 INFO - Content-Length response header: 59063022
16:43:33 INFO - Bytes received: 59063022
16:43:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.talos.tests.zip
16:43:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
16:43:44 INFO - Fetch https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.talos.tests.zip into memory
16:43:45 INFO - Content-Length response header: 18772127
16:43:45 INFO - Bytes received: 18772127
16:43: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')]}
16:43:46 INFO - trying https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2
16:43:46 INFO - Downloading https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
16:43:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
16:43:47 INFO - Downloaded 62605329 bytes.
16:43:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2
16:43:47 INFO - mkdir: /builds/slave/test/properties
16:43:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
16:43:47 INFO - Writing to file /builds/slave/test/properties/build_url
16:43:47 INFO - Contents:
16:43:47 INFO - build_url:https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2
16:43:47 INFO - Running post-action listener: _resource_record_post_action
16:43:47 INFO - Running post-action listener: find_tests_for_verification
16:43:47 INFO - Running post-action listener: set_extra_try_arguments
16:43:47 INFO - [mozharness: 2017-11-22 00:43:47.183898Z] Finished download-and-extract step (success)
16:43:47 INFO - [mozharness: 2017-11-22 00:43:47.183993Z] Running populate-webroot step.
16:43:47 INFO - Running pre-action listener: _resource_record_pre_action
16:43:47 INFO - Running main action method: populate_webroot
16:43:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
16:43:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['tresize',
16:43:47 INFO - 'tcanvasmark']},
16:43:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
16:43:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['dromaeo_css',
16:43:47 INFO - 'kraken']},
16:43:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:43:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:43:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'tests': ['damp', 'tps']},
16:43:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['damp', 'tps']},
16:43:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
16:43:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['dromaeo_dom']},
16:43:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
16:43:47 INFO - 'glvideo',
16:43:47 INFO - 'displaylist_mutate']},
16:43:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['basic_compositor_video',
16:43:47 INFO - 'glvideo']},
16:43:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
16:43:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['ts_paint_webext',
16:43:47 INFO - 'tp5o_webext']},
16:43:47 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
16:43:47 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:43:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:43:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:43:47 INFO - 'talos_options': ['--mitmproxy',
16:43:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:43:47 INFO - '--firstNonBlankPaint'],
16:43:47 INFO - 'tests': ['tp6_google_heavy',
16:43:47 INFO - 'tp6_youtube_heavy',
16:43:47 INFO - 'tp6_amazon_heavy',
16:43:47 INFO - 'tp6_facebook_heavy']},
16:43:47 INFO - 'other-e10s': {'tests': ['a11yr',
16:43:47 INFO - 'ts_paint',
16:43:47 INFO - 'tpaint',
16:43:47 INFO - 'sessionrestore',
16:43:47 INFO - 'sessionrestore_many_windows',
16:43:47 INFO - 'sessionrestore_no_auto_restore',
16:43:47 INFO - 'tabpaint',
16:43:47 INFO - 'cpstartup']},
16:43:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['a11yr',
16:43:47 INFO - 'ts_paint',
16:43:47 INFO - 'tpaint',
16:43:47 INFO - 'sessionrestore',
16:43:47 INFO - 'sessionrestore_many_windows',
16:43:47 INFO - 'sessionrestore_no_auto_restore',
16:43:47 INFO - 'tabpaint',
16:43:47 INFO - 'cpstartup']},
16:43:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
16:43:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
16:43:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['perf_reftest_singletons']},
16:43:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['perf_reftest']},
16:43:47 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
16:43:47 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['speedometer']},
16:43:47 INFO - 'svgr-e10s': {'tests': ['tsvgx',
16:43:47 INFO - 'tsvgr_opacity',
16:43:47 INFO - 'tart',
16:43:47 INFO - 'tscrollx',
16:43:47 INFO - 'tsvg_static']},
16:43:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['tsvgx',
16:43:47 INFO - 'tsvgr_opacity',
16:43:47 INFO - 'tart',
16:43:47 INFO - 'tscrollx',
16:43:47 INFO - 'tsvg_static']},
16:43:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
16:43:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'talos_options': ['--disable-stylo'],
16:43:47 INFO - 'tests': ['tp5o']},
16:43:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:43:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:43:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:43:47 INFO - 'talos_options': ['--mitmproxy',
16:43:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:43:47 INFO - '--firstNonBlankPaint'],
16:43:47 INFO - 'tests': ['tp6_google',
16:43:47 INFO - 'tp6_youtube',
16:43:47 INFO - 'tp6_amazon',
16:43:47 INFO - 'tp6_facebook']},
16:43:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:43:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:43:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:43:47 INFO - 'talos_options': ['--disable-stylo',
16:43:47 INFO - '--mitmproxy',
16:43:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:43:47 INFO - '--firstNonBlankPaint'],
16:43:47 INFO - 'tests': ['tp6_google',
16:43:47 INFO - 'tp6_youtube',
16:43:47 INFO - 'tp6_amazon',
16:43:47 INFO - 'tp6_facebook']},
16:43:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:43:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:43:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:43:47 INFO - 'talos_options': ['--stylo-threads=1',
16:43:47 INFO - '--mitmproxy',
16:43:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:43:47 INFO - '--firstNonBlankPaint'],
16:43:47 INFO - 'tests': ['tp6_google',
16:43:47 INFO - 'tp6_youtube',
16:43:47 INFO - 'tp6_amazon',
16:43:47 INFO - 'tp6_facebook']},
16:43:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'talos_options': ['--xperf_path',
16:43:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:43:47 INFO - 'tests': ['tp5n']},
16:43:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:43:47 INFO - 'talos_options': ['--disable-stylo',
16:43:47 INFO - '--xperf_path',
16:43:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:43:47 INFO - 'tests': ['tp5n']}}}
16:43:47 INFO - Running post-action listener: _resource_record_post_action
16:43:47 INFO - [mozharness: 2017-11-22 00:43:47.202602Z] Finished populate-webroot step (success)
16:43:47 INFO - [mozharness: 2017-11-22 00:43:47.202694Z] Running create-virtualenv step.
16:43:47 INFO - Running pre-action listener: _resource_record_pre_action
16:43:47 INFO - Running main action method: create_virtualenv
16:43:47 INFO - Creating virtualenv /builds/slave/test/build/venv
16:43:47 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
16:43:47 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
16:43:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
16:43:47 INFO - Using real prefix '/usr'
16:43:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python
16:43:47 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
16:43:49 INFO - Installing setuptools, pip, wheel...done.
16:43:49 INFO - Return code: 0
16:43:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
16:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:43:49 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:43:49 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
16:43:49 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
16:43:49 INFO - Using env: {'DISPLAY': ':0',
16:43:49 INFO - 'HOME': '/home/cltbld',
16:43:49 INFO - 'LANG': 'en_US.UTF-8',
16:43:49 INFO - 'LANGUAGE': 'en_US:en',
16:43:49 INFO - 'LOGNAME': 'cltbld',
16:43:49 INFO - 'MAIL': '/var/mail/cltbld',
16:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:43:49 INFO - 'MOZ_NO_REMOTE': '1',
16:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:43:49 INFO - 'NO_EM_RESTART': '1',
16:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:43:49 INFO - 'PWD': '/builds/slave/test',
16:43:49 INFO - 'SHELL': '/bin/bash',
16:43:49 INFO - 'SHLVL': '1',
16:43:49 INFO - 'TERM': 'linux',
16:43:49 INFO - 'TMOUT': '86400',
16:43:49 INFO - 'USER': 'cltbld',
16:43:49 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:43:49 INFO - '_': '/tools/buildbot/bin/python'}
16:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple
16:43:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
16:43:49 INFO - Return code: 0
16:43:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
16:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:43:49 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:43:49 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
16:43:49 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
16:43:49 INFO - Using env: {'DISPLAY': ':0',
16:43:49 INFO - 'HOME': '/home/cltbld',
16:43:49 INFO - 'LANG': 'en_US.UTF-8',
16:43:49 INFO - 'LANGUAGE': 'en_US:en',
16:43:49 INFO - 'LOGNAME': 'cltbld',
16:43:49 INFO - 'MAIL': '/var/mail/cltbld',
16:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:43:49 INFO - 'MOZ_NO_REMOTE': '1',
16:43:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:43:49 INFO - 'NO_EM_RESTART': '1',
16:43:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:43:49 INFO - 'PWD': '/builds/slave/test',
16:43:49 INFO - 'SHELL': '/bin/bash',
16:43:49 INFO - 'SHLVL': '1',
16:43:49 INFO - 'TERM': 'linux',
16:43:49 INFO - 'TMOUT': '86400',
16:43:49 INFO - 'USER': 'cltbld',
16:43:49 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:43:49 INFO - '_': '/tools/buildbot/bin/python'}
16:43:50 INFO - Ignoring indexes: https://pypi.python.org/simple
16:43:50 INFO - Collecting psutil>=3.1.1
16:43:51 INFO - Installing collected packages: psutil
16:43:51 INFO - Successfully installed psutil-3.1.1
16:43:51 INFO - Return code: 0
16:43:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
16:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:43:51 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:43:51 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
16:43:51 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
16:43:51 INFO - Using env: {'DISPLAY': ':0',
16:43:51 INFO - 'HOME': '/home/cltbld',
16:43:51 INFO - 'LANG': 'en_US.UTF-8',
16:43:51 INFO - 'LANGUAGE': 'en_US:en',
16:43:51 INFO - 'LOGNAME': 'cltbld',
16:43:51 INFO - 'MAIL': '/var/mail/cltbld',
16:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:43:51 INFO - 'MOZ_NO_REMOTE': '1',
16:43:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:43:51 INFO - 'NO_EM_RESTART': '1',
16:43:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:43:51 INFO - 'PWD': '/builds/slave/test',
16:43:51 INFO - 'SHELL': '/bin/bash',
16:43:51 INFO - 'SHLVL': '1',
16:43:51 INFO - 'TERM': 'linux',
16:43:51 INFO - 'TMOUT': '86400',
16:43:51 INFO - 'USER': 'cltbld',
16:43:51 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:43:51 INFO - '_': '/tools/buildbot/bin/python'}
16:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple
16:43:51 INFO - Collecting mozsystemmonitor==0.3
16:43:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
16:43:52 INFO - Installing collected packages: mozsystemmonitor
16:43:52 INFO - Successfully installed mozsystemmonitor-0.3
16:43:52 INFO - Return code: 0
16:43:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
16:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:43:52 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:43:52 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
16:43:52 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
16:43:52 INFO - Using env: {'DISPLAY': ':0',
16:43:52 INFO - 'HOME': '/home/cltbld',
16:43:52 INFO - 'LANG': 'en_US.UTF-8',
16:43:52 INFO - 'LANGUAGE': 'en_US:en',
16:43:52 INFO - 'LOGNAME': 'cltbld',
16:43:52 INFO - 'MAIL': '/var/mail/cltbld',
16:43:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:43:52 INFO - 'MOZ_NO_REMOTE': '1',
16:43:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:43:52 INFO - 'NO_EM_RESTART': '1',
16:43:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:43:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:43:52 INFO - 'PWD': '/builds/slave/test',
16:43:52 INFO - 'SHELL': '/bin/bash',
16:43:52 INFO - 'SHLVL': '1',
16:43:52 INFO - 'TERM': 'linux',
16:43:52 INFO - 'TMOUT': '86400',
16:43:52 INFO - 'USER': 'cltbld',
16:43:52 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:43:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:43:52 INFO - '_': '/tools/buildbot/bin/python'}
16:43:53 INFO - Ignoring indexes: https://pypi.python.org/simple
16:43:53 INFO - Collecting jsonschema==2.5.1
16:43:54 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
16:43:54 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
16:43:55 INFO - Installing collected packages: functools32, jsonschema
16:43:55 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
16:43:55 INFO - Return code: 0
16:43:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
16:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:43:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:43:55 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:43:55 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
16:43:55 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
16:43:55 INFO - Using env: {'DISPLAY': ':0',
16:43:55 INFO - 'HOME': '/home/cltbld',
16:43:55 INFO - 'LANG': 'en_US.UTF-8',
16:43:55 INFO - 'LANGUAGE': 'en_US:en',
16:43:55 INFO - 'LOGNAME': 'cltbld',
16:43:55 INFO - 'MAIL': '/var/mail/cltbld',
16:43:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:43:55 INFO - 'MOZ_NO_REMOTE': '1',
16:43:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:43:55 INFO - 'NO_EM_RESTART': '1',
16:43:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:43:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:43:55 INFO - 'PWD': '/builds/slave/test',
16:43:55 INFO - 'SHELL': '/bin/bash',
16:43:55 INFO - 'SHLVL': '1',
16:43:55 INFO - 'TERM': 'linux',
16:43:55 INFO - 'TMOUT': '86400',
16:43:55 INFO - 'USER': 'cltbld',
16:43:55 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:43:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:43:55 INFO - '_': '/tools/buildbot/bin/python'}
16:43:55 INFO - Ignoring indexes: https://pypi.python.org/simple
16:43:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
16:43:56 INFO - Return code: 0
16:43:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
16:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:43:56 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:43:56 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
16:43:56 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
16:43:56 INFO - Using env: {'DISPLAY': ':0',
16:43:56 INFO - 'HOME': '/home/cltbld',
16:43:56 INFO - 'LANG': 'en_US.UTF-8',
16:43:56 INFO - 'LANGUAGE': 'en_US:en',
16:43:56 INFO - 'LOGNAME': 'cltbld',
16:43:56 INFO - 'MAIL': '/var/mail/cltbld',
16:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:43:56 INFO - 'MOZ_NO_REMOTE': '1',
16:43:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:43:56 INFO - 'NO_EM_RESTART': '1',
16:43:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:43:56 INFO - 'PWD': '/builds/slave/test',
16:43:56 INFO - 'SHELL': '/bin/bash',
16:43:56 INFO - 'SHLVL': '1',
16:43:56 INFO - 'TERM': 'linux',
16:43:56 INFO - 'TMOUT': '86400',
16:43:56 INFO - 'USER': 'cltbld',
16:43:56 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:43:56 INFO - '_': '/tools/buildbot/bin/python'}
16:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple
16:43:56 INFO - Collecting blobuploader==1.2.4
16:43:57 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
16:43:58 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
16:43:59 INFO - Installing collected packages: requests, docopt, blobuploader
16:44:00 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
16:44:00 INFO - Return code: 0
16:44:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:44:00 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:44:00 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
16:44:00 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
16:44:00 INFO - Using env: {'DISPLAY': ':0',
16:44:00 INFO - 'HOME': '/home/cltbld',
16:44:00 INFO - 'LANG': 'en_US.UTF-8',
16:44:00 INFO - 'LANGUAGE': 'en_US:en',
16:44:00 INFO - 'LOGNAME': 'cltbld',
16:44:00 INFO - 'MAIL': '/var/mail/cltbld',
16:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:44:00 INFO - 'MOZ_NO_REMOTE': '1',
16:44:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:44:00 INFO - 'NO_EM_RESTART': '1',
16:44:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:44:00 INFO - 'PWD': '/builds/slave/test',
16:44:00 INFO - 'SHELL': '/bin/bash',
16:44:00 INFO - 'SHLVL': '1',
16:44:00 INFO - 'TERM': 'linux',
16:44:00 INFO - 'TMOUT': '86400',
16:44:00 INFO - 'USER': 'cltbld',
16:44:00 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:44:00 INFO - '_': '/tools/buildbot/bin/python'}
16:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple
16:44:00 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
16:44:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
16:44:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
16:44:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
16:44:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
16:44:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
16:44:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
16:44:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
16:44:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
16:44:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
16:44:02 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
16:44:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
16:44:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
16:44:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
16:44:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
16:44:03 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
16:44:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
16:44:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
16:44:03 INFO - Running setup.py install for manifestparser: started
16:44:04 INFO - Running setup.py install for manifestparser: finished with status 'done'
16:44:04 INFO - Running setup.py install for mozcrash: started
16:44:04 INFO - Running setup.py install for mozcrash: finished with status 'done'
16:44:04 INFO - Running setup.py install for mozdebug: started
16:44:04 INFO - Running setup.py install for mozdebug: finished with status 'done'
16:44:04 INFO - Running setup.py install for mozdevice: started
16:44:04 INFO - Running setup.py install for mozdevice: finished with status 'done'
16:44:04 INFO - Running setup.py install for mozfile: started
16:44:04 INFO - Running setup.py install for mozfile: finished with status 'done'
16:44:04 INFO - Running setup.py install for mozhttpd: started
16:44:05 INFO - Running setup.py install for mozhttpd: finished with status 'done'
16:44:05 INFO - Running setup.py install for mozinfo: started
16:44:05 INFO - Running setup.py install for mozinfo: finished with status 'done'
16:44:05 INFO - Running setup.py install for mozInstall: started
16:44:05 INFO - Running setup.py install for mozInstall: finished with status 'done'
16:44:05 INFO - Running setup.py install for mozleak: started
16:44:05 INFO - Running setup.py install for mozleak: finished with status 'done'
16:44:05 INFO - Running setup.py install for mozlog: started
16:44:05 INFO - Running setup.py install for mozlog: finished with status 'done'
16:44:05 INFO - Running setup.py install for moznetwork: started
16:44:06 INFO - Running setup.py install for moznetwork: finished with status 'done'
16:44:06 INFO - Running setup.py install for mozprocess: started
16:44:06 INFO - Running setup.py install for mozprocess: finished with status 'done'
16:44:06 INFO - Running setup.py install for mozprofile: started
16:44:06 INFO - Running setup.py install for mozprofile: finished with status 'done'
16:44:06 INFO - Running setup.py install for mozrunner: started
16:44:06 INFO - Running setup.py install for mozrunner: finished with status 'done'
16:44:06 INFO - Running setup.py install for mozscreenshot: started
16:44:07 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
16:44:07 INFO - Running setup.py install for moztest: started
16:44:07 INFO - Running setup.py install for moztest: finished with status 'done'
16:44:07 INFO - Running setup.py install for mozversion: started
16:44:07 INFO - Running setup.py install for mozversion: finished with status 'done'
16:44:07 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
16:44:07 INFO - Return code: 0
16:44:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:44:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:44:07 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:44:07 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
16:44:07 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
16:44:07 INFO - Using env: {'DISPLAY': ':0',
16:44:07 INFO - 'HOME': '/home/cltbld',
16:44:07 INFO - 'LANG': 'en_US.UTF-8',
16:44:07 INFO - 'LANGUAGE': 'en_US:en',
16:44:07 INFO - 'LOGNAME': 'cltbld',
16:44:07 INFO - 'MAIL': '/var/mail/cltbld',
16:44:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:44:07 INFO - 'MOZ_NO_REMOTE': '1',
16:44:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:44:07 INFO - 'NO_EM_RESTART': '1',
16:44:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:44:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:44:07 INFO - 'PWD': '/builds/slave/test',
16:44:07 INFO - 'SHELL': '/bin/bash',
16:44:07 INFO - 'SHLVL': '1',
16:44:07 INFO - 'TERM': 'linux',
16:44:07 INFO - 'TMOUT': '86400',
16:44:07 INFO - 'USER': 'cltbld',
16:44:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:44:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:44:07 INFO - '_': '/tools/buildbot/bin/python'}
16:44:08 INFO - Ignoring indexes: https://pypi.python.org/simple
16:44:08 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
16:44:08 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))
16:44:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
16:44:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:44:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
16:44:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
16:44:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
16:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:44:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
16:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
16:44:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
16:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
16:44:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
16:44:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
16:44:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
16:44:09 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))
16:44:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
16:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
16:44:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
16:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
16:44:10 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
16:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
16:44:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
16:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
16:44:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
16:44:10 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))
16:44:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
16:44:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
16:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
16:44:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
16:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
16:44:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
16:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
16:44:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
16:44:11 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
16:44:12 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
16:44:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:44:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:44:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
16:44:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:44:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:44:12 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))
16:44:12 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
16:44:13 INFO - Installing collected packages: six, blessings
16:44:13 INFO - Successfully installed blessings-1.6 six-1.10.0
16:44:14 INFO - Return code: 0
16:44:14 INFO - Done creating virtualenv /builds/slave/test/build/venv.
16:44:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:44:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:44:14 INFO - Reading from file tmpfile_stdout
16:44:14 INFO - Reading from file tmpfile_stderr
16:44:14 INFO - Current package versions:
16:44:14 INFO - blessings == 1.6
16:44:14 INFO - blobuploader == 1.2.4
16:44:14 INFO - docopt == 0.6.1
16:44:14 INFO - functools32 == 3.2.3.post2
16:44:14 INFO - jsonschema == 2.5.1
16:44:14 INFO - manifestparser == 1.2
16:44:14 INFO - mozInstall == 1.14
16:44:14 INFO - mozcrash == 1.0
16:44:14 INFO - mozdebug == 0.1
16:44:14 INFO - mozdevice == 0.51
16:44:14 INFO - mozfile == 1.2
16:44:14 INFO - mozhttpd == 0.7
16:44:14 INFO - mozinfo == 0.10
16:44:14 INFO - mozleak == 0.1
16:44:14 INFO - mozlog == 3.5
16:44:14 INFO - moznetwork == 0.27
16:44:14 INFO - mozprocess == 0.25
16:44:14 INFO - mozprofile == 0.29
16:44:14 INFO - mozrunner == 6.13
16:44:14 INFO - mozscreenshot == 0.1
16:44:14 INFO - mozsystemmonitor == 0.3
16:44:14 INFO - moztest == 0.8
16:44:14 INFO - mozversion == 1.4
16:44:14 INFO - psutil == 3.1.1
16:44:14 INFO - requests == 1.2.3
16:44:14 INFO - six == 1.10.0
16:44:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:44:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:44:14 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:44:14 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
16:44:14 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
16:44:14 INFO - Using env: {'DISPLAY': ':0',
16:44:14 INFO - 'HOME': '/home/cltbld',
16:44:14 INFO - 'LANG': 'en_US.UTF-8',
16:44:14 INFO - 'LANGUAGE': 'en_US:en',
16:44:14 INFO - 'LOGNAME': 'cltbld',
16:44:14 INFO - 'MAIL': '/var/mail/cltbld',
16:44:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:44:14 INFO - 'MOZ_NO_REMOTE': '1',
16:44:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:44:14 INFO - 'NO_EM_RESTART': '1',
16:44:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:44:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:44:14 INFO - 'PWD': '/builds/slave/test',
16:44:14 INFO - 'SHELL': '/bin/bash',
16:44:14 INFO - 'SHLVL': '1',
16:44:14 INFO - 'TERM': 'linux',
16:44:14 INFO - 'TMOUT': '86400',
16:44:14 INFO - 'USER': 'cltbld',
16:44:14 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:44:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:44:14 INFO - '_': '/tools/buildbot/bin/python'}
16:44:15 INFO - Ignoring indexes: https://pypi.python.org/simple
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
16:44:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
16:44:15 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
16:44:16 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:44:17 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
16:44:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
16:44:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
16:44:17 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))
16:44:17 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))
16:44:18 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
16:44:18 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:44:19 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
16:44:19 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:44:20 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
16:44:20 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:44:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
16:44:21 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
16:44:21 INFO - Found existing installation: requests 1.2.3
16:44:21 INFO - Uninstalling requests-1.2.3:
16:44:21 INFO - Successfully uninstalled requests-1.2.3
16:44:21 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
16:44:21 INFO - Return code: 0
16:44:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
16:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:44:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:44:21 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 0x12afd50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x128ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14301a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, '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': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:44:21 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
16:44:21 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
16:44:21 INFO - Using env: {'DISPLAY': ':0',
16:44:21 INFO - 'HOME': '/home/cltbld',
16:44:21 INFO - 'LANG': 'en_US.UTF-8',
16:44:21 INFO - 'LANGUAGE': 'en_US:en',
16:44:21 INFO - 'LOGNAME': 'cltbld',
16:44:21 INFO - 'MAIL': '/var/mail/cltbld',
16:44:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:44:21 INFO - 'MOZ_NO_REMOTE': '1',
16:44:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:44:21 INFO - 'NO_EM_RESTART': '1',
16:44:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:44:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:44:21 INFO - 'PWD': '/builds/slave/test',
16:44:21 INFO - 'SHELL': '/bin/bash',
16:44:21 INFO - 'SHLVL': '1',
16:44:21 INFO - 'TERM': 'linux',
16:44:21 INFO - 'TMOUT': '86400',
16:44:21 INFO - 'USER': 'cltbld',
16:44:21 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:44:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:44:21 INFO - '_': '/tools/buildbot/bin/python'}
16:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple
16:44:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
16:44:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
16:44:22 INFO - Return code: 0
16:44:22 INFO - Running post-action listener: _resource_record_post_action
16:44:22 INFO - Running post-action listener: _start_resource_monitoring
16:44:22 INFO - Starting resource monitoring.
16:44:22 INFO - [mozharness: 2017-11-22 00:44:22.452552Z] Finished create-virtualenv step (success)
16:44:22 INFO - [mozharness: 2017-11-22 00:44:22.453066Z] Running install step.
16:44:22 INFO - Running pre-action listener: _resource_record_pre_action
16:44:22 INFO - Running main action method: install
16:44:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:44:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:44:22 INFO - Reading from file tmpfile_stdout
16:44:22 INFO - Reading from file tmpfile_stderr
16:44:22 INFO - Detecting whether we're running mozinstall >=1.0...
16:44:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
16:44:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
16:44:23 INFO - Reading from file tmpfile_stdout
16:44:23 INFO - Output received:
16:44:23 INFO - Usage: mozinstall [options] installer
16:44:23 INFO - Options:
16:44:23 INFO - -h, --help show this help message and exit
16:44:23 INFO - -d DEST, --destination=DEST
16:44:23 INFO - Directory to install application into. [default:
16:44:23 INFO - "/builds/slave/test"]
16:44:23 INFO - --app=APP Application being installed. [default: firefox]
16:44:23 INFO - mkdir: /builds/slave/test/build/application
16:44:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
16:44:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
16:44:41 INFO - Reading from file tmpfile_stdout
16:44:41 INFO - Output received:
16:44:41 INFO - /builds/slave/test/build/application/firefox/firefox
16:44:41 INFO - Running post-action listener: _resource_record_post_action
16:44:41 INFO - [mozharness: 2017-11-22 00:44:41.432793Z] Finished install step (success)
16:44:41 INFO - [mozharness: 2017-11-22 00:44:41.432941Z] Running setup-mitmproxy step.
16:44:41 INFO - Running pre-action listener: _resource_record_pre_action
16:44:41 INFO - Running main action method: setup_mitmproxy
16:44:41 INFO - Skipping: mitmproxy is not required
16:44:41 INFO - Running post-action listener: _resource_record_post_action
16:44:41 INFO - [mozharness: 2017-11-22 00:44:41.433453Z] Finished setup-mitmproxy step (success)
16:44:41 INFO - [mozharness: 2017-11-22 00:44:41.433555Z] Running run-tests step.
16:44:41 INFO - Running pre-action listener: _resource_record_pre_action
16:44:41 INFO - Running pre-action listener: _set_gcov_prefix
16:44:41 INFO - Running main action method: run_tests
16:44:41 WARNING - Try message not found.
16:44:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
16:44:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
16:44:41 INFO - Python 2.7.3
16:44:41 INFO - Return code: 0
16:44:41 INFO - grabbing minidump binary from tooltool
16:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:44:41 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 0x142d320>, '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 0x142e220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x142e6d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
16:44:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
16:44:41 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
16:44:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
16:44:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
16:44:41 INFO - Return code: 0
16:44:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
16:44:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
16:44:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
16:44:41 INFO - ENV: RUST_BACKTRACE is now full
16:44:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
16:44:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
16:44:41 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/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
16:44:41 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/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
16:44:41 INFO - Using env: {'DISPLAY': ':0',
16:44:41 INFO - 'HOME': '/home/cltbld',
16:44:41 INFO - 'LANG': 'en_US.UTF-8',
16:44:41 INFO - 'LANGUAGE': 'en_US:en',
16:44:41 INFO - 'LOGNAME': 'cltbld',
16:44:41 INFO - 'MAIL': '/var/mail/cltbld',
16:44:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:44:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:44:41 INFO - 'MOZ_ACCELERATED': '1',
16:44:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:44:41 INFO - 'MOZ_NO_REMOTE': '1',
16:44:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:44:41 INFO - 'MOZ_WEBRENDER': '1',
16:44:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:44:41 INFO - 'NO_EM_RESTART': '1',
16:44:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:44:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:44:41 INFO - 'PWD': '/builds/slave/test',
16:44:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:44:41 INFO - 'RUST_BACKTRACE': 'full',
16:44:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:44:41 INFO - 'SHELL': '/bin/bash',
16:44:41 INFO - 'SHLVL': '1',
16:44:41 INFO - 'TERM': 'linux',
16:44:41 INFO - 'TMOUT': '86400',
16:44:41 INFO - 'USER': 'cltbld',
16:44:41 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:44:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:44:41 INFO - '_': '/tools/buildbot/bin/python'}
16:44:41 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/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
16:44:41 INFO - mozversion application_buildid: 20171121233439
16:44:41 INFO - mozversion application_changeset: b96f009478987d44a68a8d7cad40c6a3d6626235
16:44:41 INFO - mozversion application_display_name: Nightly
16:44:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
16:44:41 INFO - mozversion application_name: Firefox
16:44:41 INFO - mozversion application_remotingname: firefox
16:44:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
16:44:41 INFO - mozversion application_vendor: Mozilla
16:44:41 INFO - mozversion application_version: 59.0a1
16:44:41 INFO - mozversion platform_buildid: 20171121233439
16:44:41 INFO - mozversion platform_changeset: b96f009478987d44a68a8d7cad40c6a3d6626235
16:44:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
16:44:41 INFO - mozversion platform_version: 59.0a1
16:44:41 INFO - using testdate: 1511311481
16:44:41 INFO - actual date: 1511311481
16:44:41 INFO - starting webserver on 'localhost:51755'
16:44:41 INFO - SUITE-START | Running 3 tests
16:44:41 INFO - TEST-START | basic_compositor_video
16:44:41 INFO - Initialising browser for basic_compositor_video test...
16:44:41 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
16:44:41 INFO - Installing Add-ons
16:44:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51755/getInfo.html -profile /tmp/tmp0yeMNk/profile
16:44:41 INFO - TEST-INFO | started process 1533 (/builds/slave/test/build/application/firefox/firefox http://localhost:51755/getInfo.html)
16:44:48 INFO - TEST-INFO | 1533: exit 0
16:44:48 INFO - Browser initialized.
16:44:48 INFO - Running cycle 1/1 for basic_compositor_video test...
16:44:48 INFO - Using env: {'DISPLAY': ':0',
16:44:48 INFO - 'HOME': '/home/cltbld',
16:44:48 INFO - 'JSGC_DISABLE_POISONING': '1',
16:44:48 INFO - 'LANG': 'en_US.UTF-8',
16:44:48 INFO - 'LANGUAGE': 'en_US:en',
16:44:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:44:48 INFO - 'LOGNAME': 'cltbld',
16:44:48 INFO - 'MAIL': '/var/mail/cltbld',
16:44:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:44:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:44:48 INFO - 'MOZ_ACCELERATED': '1',
16:44:48 INFO - 'MOZ_CRASHREPORTER': '1',
16:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:44:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:44:48 INFO - 'MOZ_NO_REMOTE': '1',
16:44:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:44:48 INFO - 'MOZ_WEBRENDER': '1',
16:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:44:48 INFO - 'NO_EM_RESTART': '1',
16:44:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:44:48 INFO - 'PWD': '/builds/slave/test',
16:44:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:44:48 INFO - 'RUST_BACKTRACE': 'full',
16:44:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:44:48 INFO - 'SHELL': '/bin/bash',
16:44:48 INFO - 'SHLVL': '1',
16:44:48 INFO - 'TERM': 'linux',
16:44:48 INFO - 'TMOUT': '86400',
16:44:48 INFO - 'USER': 'cltbld',
16:44:48 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:44:48 INFO - '_': '/tools/buildbot/bin/python'}
16:44:48 INFO - TEST-INFO | started process 1814 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0yeMNk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
16:44:48 INFO - PID 1814 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
16:44:49 INFO - PID 1814 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
16:44:49 INFO - PID 1814 |
16:44:49 INFO - PID 1814 | (/builds/slave/test/build/application/firefox/firefox:1877): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:44:49 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.5257954545454546 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460313653136532 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5211302521008405 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.498027465667914 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.09358762886598 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0637672281776407 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.179094707520894 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.108049281314166 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.525731523378584 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.54655681818182 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.718498427672956 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673738317757011 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011880841121493 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.780966101694922 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.961519721577728 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7570439189189155 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.027985948477745 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.782593220339001 ms/frame
16:45:35 INFO - PID 1814 |
16:45:35 INFO - PID 1814 | Cycle 1(1): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:46:21 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4940440565253534 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4608302583025834 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.510933054393306 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503554443053816 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0936649484536076 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0770230769230777 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.196349650349649 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.099989754098365 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.249893767705381 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.220263713080174 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.78468102073365 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.740699052132703 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.026756440281028 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.780966101694922 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.076356132075472 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.803486394557818 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.946076388888893 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.734966329966317 ms/frame
16:46:21 INFO - PID 1814 |
16:46:21 INFO - PID 1814 | Cycle 1(2): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:46:23 INFO - PID 1814 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
16:47:07 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.5317763713080166 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.469956790123457 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.51684144295302 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5005812500000015 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.089871266735326 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0637595712097982 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.156011080332411 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.124216494845359 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.498973013493257 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.496269662921349 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.687914062500004 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.66265734265734 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011471962616831 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.850513698630132 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.043579812206578 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.803962585034006 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.998554778554789 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.783033898305069 ms/frame
16:47:07 INFO - PID 1814 |
16:47:07 INFO - PID 1814 | Cycle 1(3): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:47:53 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.485509527754764 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.457082309582309 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.511058577405858 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5095545796737766 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0804876796714566 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.06362940275651 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.525037707390652 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.476510067114094 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.317071942446041 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.3479021739130435 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.688156249999997 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.652569767441859 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.994662004662011 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.758868243243243 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010876168224296 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.803775510204098 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.02655737704919 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.781220338983048 ms/frame
16:47:53 INFO - PID 1814 |
16:47:53 INFO - PID 1814 | Cycle 1(4): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:48:39 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4837003311258283 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4603382533825338 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5174244966442956 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5068859649122808 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0801283367556467 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0726804915514574 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.5675494672754935 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.79685851318945 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.406049926578562 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.339360086767895 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.687796875000004 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.651918604651156 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.979127906976748 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.757787162162163 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.977406976744194 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.759459459459444 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.010151869158881 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.759898648648649 ms/frame
16:48:39 INFO - PID 1814 |
16:48:39 INFO - PID 1814 | Cycle 1(5): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:49:25 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.48001652892562 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4609102091020914 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5086036789297657 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5030913642052557 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.08355601233299 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0733717357910897 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.190858938547487 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.098483606557379 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.219810126582279 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.20196428571429 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.652759689922479 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.641044083526684 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011168224299071 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.735572390572379 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.982081395348833 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.735016835016845 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.026030444964866 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.803044217687079 ms/frame
16:49:25 INFO - PID 1814 |
16:49:25 INFO - PID 1814 | Cycle 1(6): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:50:11 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4797561983471077 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.463245073891626 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5106694560669456 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5036170212765962 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0738012295081973 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.067852760736196 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.16685416666667 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.1340392561983474 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.225464788732392 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.167958333333339 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.695790297339594 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.653302325581391 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.993764568764562 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.826348122866903 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010724299065429 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.827969283276454 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.993414918414925 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780084745762712 ms/frame
16:50:11 INFO - PID 1814 |
16:50:11 INFO - PID 1814 | Cycle 1(7): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:50:57 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.498526228143214 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4552576687116563 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5191057934508816 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5030225281602 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.080805954825462 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0726881720430104 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.368326055312957 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.3310822510822495 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.35537010159652 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.256840425531915 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.807948717948718 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.752280285035627 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.994953379953378 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.757922297297301 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.03110070257611 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.781288135593207 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.010385514018691 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.7584797297297365 ms/frame
16:50:57 INFO - PID 1814 |
16:50:57 INFO - PID 1814 | Cycle 1(8): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:51:43 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4859527754763877 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.463676108374385 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5128768844221105 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5010062500000014 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0778205128205127 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.062848392036755 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.406534508076361 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.264413646055433 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.552769347496208 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.535759637188209 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.847487883683362 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.773233890214798 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.010245327102808 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.75777027027028 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.94435185185185 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758868243243243 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.977872093023257 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.804540816326543 ms/frame
16:51:43 INFO - PID 1814 |
16:51:43 INFO - PID 1814 | Cycle 1(9): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:52:29 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.488097014925373 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.466430332922319 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.514945515507125 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503153942428036 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0810112936344978 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0688726993865014 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.361642441860462 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.32011879049676 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.298918338108884 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.203098739495797 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.703620689655172 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.662890442890445 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011775700934573 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.7803220338983285 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.97750000000001 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.781966101694891 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.962273781902548 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.7834237288135615 ms/frame
16:52:29 INFO - PID 1814 |
16:52:29 INFO - PID 1814 | Cycle 1(10): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:53:15 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.479880165289256 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4572542997543003 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5192989084802693 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.504224030037547 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.083895169578624 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.077630769230768 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.237577683615819 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.201995798319323 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.412838235294117 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.456481069042322 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.717845911949684 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.66319347319347 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.960661252900228 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.780525423728805 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.994673659673668 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.828532423208198 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.9451736111111 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780084745762712 ms/frame
16:53:15 INFO - PID 1814 |
16:53:15 INFO - PID 1814 | Cycle 1(11): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:54:01 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_startup = 2.481976840363937 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4699197530864194 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5090677257525087 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.50129375 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_startup = 3.077538461538462 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.068880368098159 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_startup = 4.601756134969327 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1_inclip = 4.619515011547344 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.5319108761329305 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.4463777777777755 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_startup = 4.687789062500002 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.480p.60fps.webm_scale_2_inclip = 4.663566433566438 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.995279720279717 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.849948630137003 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.026686182669799 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758412162162156 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.027388758782201 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.78130508474574 ms/frame
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | Cycle 1(12): loaded http://localhost:51755/tests/video/video_playback.html (next: http://localhost:51755/tests/video/video_playback.html)
16:54:01 INFO - PID 1814 | __start_tp_report
16:54:01 INFO - PID 1814 | _x_x_mozilla_page_load
16:54:01 INFO - PID 1814 | _x_x_mozilla_page_load_details
16:54:01 INFO - PID 1814 | |i|pagename|runs|
16:54:01 INFO - PID 1814 | |0;240p.120fps.mp4_scale_1_startup;2.5257954545454546;2.4940440565253534;2.5317763713080166;2.485509527754764;2.4837003311258283;2.48001652892562;2.4797561983471077;2.498526228143214;2.4859527754763877;2.488097014925373;2.479880165289256;2.481976840363937
16:54:01 INFO - PID 1814 | |1;240p.120fps.mp4_scale_1_inclip;2.460313653136532;2.4608302583025834;2.469956790123457;2.457082309582309;2.4603382533825338;2.4609102091020914;2.463245073891626;2.4552576687116563;2.463676108374385;2.466430332922319;2.4572542997543003;2.4699197530864194
16:54:01 INFO - PID 1814 | |2;240p.120fps.mp4_scale_1.1_startup;2.5211302521008405;2.510933054393306;2.51684144295302;2.511058577405858;2.5174244966442956;2.5086036789297657;2.5106694560669456;2.5191057934508816;2.5128768844221105;2.514945515507125;2.5192989084802693;2.5090677257525087
16:54:01 INFO - PID 1814 | |3;240p.120fps.mp4_scale_1.1_inclip;2.498027465667914;2.503554443053816;2.5005812500000015;2.5095545796737766;2.5068859649122808;2.5030913642052557;2.5036170212765962;2.5030225281602;2.5010062500000014;2.503153942428036;2.504224030037547;2.50129375
16:54:01 INFO - PID 1814 | |4;240p.120fps.mp4_scale_2_startup;3.09358762886598;3.0936649484536076;3.089871266735326;3.0804876796714566;3.0801283367556467;3.08355601233299;3.0738012295081973;3.080805954825462;3.0778205128205127;3.0810112936344978;3.083895169578624;3.077538461538462
16:54:01 INFO - PID 1814 | |5;240p.120fps.mp4_scale_2_inclip;3.0637672281776407;3.0770230769230777;3.0637595712097982;3.06362940275651;3.0726804915514574;3.0733717357910897;3.067852760736196;3.0726881720430104;3.062848392036755;3.0688726993865014;3.077630769230768;3.068880368098159
16:54:01 INFO - PID 1814 | |6;480p.60fps.webm_scale_1_startup;4.179094707520894;4.196349650349649;4.156011080332411;4.525037707390652;4.5675494672754935;4.190858938547487;4.16685416666667;4.368326055312957;4.406534508076361;4.361642441860462;4.237577683615819;4.601756134969327
16:54:01 INFO - PID 1814 | |7;480p.60fps.webm_scale_1_inclip;4.108049281314166;4.099989754098365;4.124216494845359;4.476510067114094;4.79685851318945;4.098483606557379;4.1340392561983474;4.3310822510822495;4.264413646055433;4.32011879049676;4.201995798319323;4.619515011547344
16:54:01 INFO - PID 1814 | |8;480p.60fps.webm_scale_1.1_startup;4.525731523378584;4.249893767705381;4.498973013493257;4.317071942446041;4.406049926578562;4.219810126582279;4.225464788732392;4.35537010159652;4.552769347496208;4.298918338108884;4.412838235294117;4.5319108761329305
16:54:01 INFO - PID 1814 | |9;480p.60fps.webm_scale_1.1_inclip;4.54655681818182;4.220263713080174;4.496269662921349;4.3479021739130435;4.339360086767895;4.20196428571429;4.167958333333339;4.256840425531915;4.535759637188209;4.203098739495797;4.456481069042322;4.4463777777777755
16:54:01 INFO - PID 1814 | |10;480p.60fps.webm_scale_2_startup;4.718498427672956;4.78468102073365;4.687914062500004;4.688156249999997;4.687796875000004;4.652759689922479;4.695790297339594;4.807948717948718;4.847487883683362;4.703620689655172;4.717845911949684;4.687789062500002
16:54:01 INFO - PID 1814 | |11;480p.60fps.webm_scale_2_inclip;4.673738317757011;4.740699052132703;4.66265734265734;4.652569767441859;4.651918604651156;4.641044083526684;4.653302325581391;4.752280285035627;4.773233890214798;4.662890442890445;4.66319347319347;4.663566433566438
16:54:01 INFO - PID 1814 | |12;1080p.60fps.mp4_scale_1_startup;7.011880841121493;7.026756440281028;7.011471962616831;6.994662004662011;6.979127906976748;7.011168224299071;6.993764568764562;6.994953379953378;7.010245327102808;7.011775700934573;6.960661252900228;6.995279720279717
16:54:01 INFO - PID 1814 | |13;1080p.60fps.mp4_scale_1_inclip;6.780966101694922;6.780966101694922;6.850513698630132;6.758868243243243;6.757787162162163;6.735572390572379;6.826348122866903;6.757922297297301;6.75777027027028;6.7803220338983285;6.780525423728805;6.849948630137003
16:54:01 INFO - PID 1814 | |14;1080p.60fps.mp4_scale_1.1_startup;6.961519721577728;7.076356132075472;7.043579812206578;7.010876168224296;6.977406976744194;6.982081395348833;7.010724299065429;7.03110070257611;6.94435185185185;6.97750000000001;6.994673659673668;7.026686182669799
16:54:01 INFO - PID 1814 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.7570439189189155;6.803486394557818;6.803962585034006;6.803775510204098;6.759459459459444;6.735016835016845;6.827969283276454;6.781288135593207;6.758868243243243;6.781966101694891;6.828532423208198;6.758412162162156
16:54:01 INFO - PID 1814 | |16;1080p.60fps.mp4_scale_2_startup;7.027985948477745;6.946076388888893;6.998554778554789;7.02655737704919;7.010151869158881;7.026030444964866;6.993414918414925;7.010385514018691;6.977872093023257;6.962273781902548;6.9451736111111;7.027388758782201
16:54:01 INFO - PID 1814 | |17;1080p.60fps.mp4_scale_2_inclip;6.782593220339001;6.734966329966317;6.783033898305069;6.781220338983048;6.759898648648649;6.803044217687079;6.780084745762712;6.7584797297297365;6.804540816326543;6.7834237288135615;6.780084745762712;6.78130508474574
16:54:01 INFO - PID 1814 | __end_tp_report
16:54:01 INFO - PID 1814 | __start_cc_report
16:54:01 INFO - PID 1814 | _x_x_mozilla_cycle_collect,632
16:54:01 INFO - PID 1814 | __end_cc_report
16:54:01 INFO - PID 1814 | __startTimestamp1511312041483__endTimestamp
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | ------- Summary: start -------
16:54:01 INFO - PID 1814 | Number of tests: 18
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.49 Median:2.49 stddev:0.02 (0.7%) stddev-sans-first:0.02
16:54:01 INFO - PID 1814 | 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
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.46 Median:2.46 stddev:0.00 (0.2%) stddev-sans-first:0.00
16:54:01 INFO - PID 1814 | 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
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.51 Median:2.52 stddev:0.00 (0.2%) stddev-sans-first:0.00
16:54:01 INFO - PID 1814 | 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
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.50 Median:2.50 stddev:0.00 (0.1%) stddev-sans-first:0.00
16:54:01 INFO - PID 1814 | 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
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.08 Median:3.08 stddev:0.01 (0.2%) stddev-sans-first:0.01
16:54:01 INFO - PID 1814 | 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
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.07 Median:3.07 stddev:0.01 (0.2%) stddev-sans-first:0.01
16:54:01 INFO - PID 1814 | 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
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.33 Median:4.36 stddev:0.17 (3.8%) stddev-sans-first:0.17
16:54:01 INFO - PID 1814 | Values: 4.2 4.2 4.2 4.5 4.6 4.2 4.2 4.4 4.4 4.4 4.2 4.6
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.30 Median:4.29 stddev:0.23 (5.3%) stddev-sans-first:0.23
16:54:01 INFO - PID 1814 | Values: 4.1 4.1 4.1 4.5 4.8 4.1 4.1 4.3 4.3 4.3 4.2 4.6
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.38 Median:4.41 stddev:0.12 (2.8%) stddev-sans-first:0.12
16:54:01 INFO - PID 1814 | Values: 4.5 4.2 4.5 4.3 4.4 4.2 4.2 4.4 4.6 4.3 4.4 4.5
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.35 Median:4.40 stddev:0.14 (3.2%) stddev-sans-first:0.13
16:54:01 INFO - PID 1814 | Values: 4.5 4.2 4.5 4.3 4.3 4.2 4.2 4.3 4.5 4.2 4.5 4.4
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.72 Median:4.71 stddev:0.06 (1.2%) stddev-sans-first:0.06
16:54:01 INFO - PID 1814 | Values: 4.7 4.8 4.7 4.7 4.7 4.7 4.7 4.8 4.8 4.7 4.7 4.7
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.68 Median:4.66 stddev:0.05 (1.0%) stddev-sans-first:0.05
16:54:01 INFO - PID 1814 | Values: 4.7 4.7 4.7 4.7 4.7 4.6 4.7 4.8 4.8 4.7 4.7 4.7
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.00 Median:7.01 stddev:0.02 (0.3%) stddev-sans-first:0.02
16:54:01 INFO - PID 1814 | Values: 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.78 Median:6.78 stddev:0.04 (0.6%) stddev-sans-first:0.04
16:54:01 INFO - PID 1814 | Values: 6.8 6.8 6.9 6.8 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.00 Median:7.01 stddev:0.04 (0.5%) stddev-sans-first:0.04
16:54:01 INFO - PID 1814 | Values: 7.0 7.1 7.0 7.0 7.0 7.0 7.0 7.0 6.9 7.0 7.0 7.0
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.78 Median:6.79 stddev:0.03 (0.4%) stddev-sans-first:0.03
16:54:01 INFO - PID 1814 | Values: 6.8 6.8 6.8 6.8 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.00 Median:7.01 stddev:0.03 (0.4%) stddev-sans-first:0.03
16:54:01 INFO - PID 1814 | Values: 7.0 6.9 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 6.9 7.0
16:54:01 INFO - PID 1814 |
16:54:01 INFO - PID 1814 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.78 Median:6.78 stddev:0.02 (0.3%) stddev-sans-first:0.02
16:54:01 INFO - PID 1814 | Values: 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8
16:54:01 INFO - PID 1814 | -------- Summary: end --------
16:54:01 INFO - PID 1814 |
16:54:02 INFO - TEST-INFO | 1814: exit 0
16:54:02 INFO - TEST-OK | basic_compositor_video | took 560365ms
16:54:02 INFO - TEST-START | glvideo
16:54:02 INFO - Initialising browser for glvideo test...
16:54:02 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
16:54:02 INFO - Installing Add-ons
16:54:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51755/getInfo.html -profile /tmp/tmpBL9W4N/profile
16:54:02 INFO - TEST-INFO | started process 3273 (/builds/slave/test/build/application/firefox/firefox http://localhost:51755/getInfo.html)
16:54:08 INFO - TEST-INFO | 3273: exit 0
16:54:08 INFO - Browser initialized.
16:54:08 INFO - Running cycle 1/1 for glvideo test...
16:54:08 INFO - Using env: {'DISPLAY': ':0',
16:54:08 INFO - 'HOME': '/home/cltbld',
16:54:08 INFO - 'JSGC_DISABLE_POISONING': '1',
16:54:08 INFO - 'LANG': 'en_US.UTF-8',
16:54:08 INFO - 'LANGUAGE': 'en_US:en',
16:54:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:54:08 INFO - 'LOGNAME': 'cltbld',
16:54:08 INFO - 'MAIL': '/var/mail/cltbld',
16:54:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:54:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:54:08 INFO - 'MOZ_ACCELERATED': '1',
16:54:08 INFO - 'MOZ_CRASHREPORTER': '1',
16:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:54:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:54:08 INFO - 'MOZ_NO_REMOTE': '1',
16:54:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:54:08 INFO - 'MOZ_WEBRENDER': '1',
16:54:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:54:08 INFO - 'NO_EM_RESTART': '1',
16:54:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:54:08 INFO - 'PWD': '/builds/slave/test',
16:54:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:54:08 INFO - 'RUST_BACKTRACE': 'full',
16:54:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:54:08 INFO - 'SHELL': '/bin/bash',
16:54:08 INFO - 'SHLVL': '1',
16:54:08 INFO - 'TERM': 'linux',
16:54:08 INFO - 'TMOUT': '86400',
16:54:08 INFO - 'USER': 'cltbld',
16:54:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:54:08 INFO - '_': '/tools/buildbot/bin/python'}
16:54:08 INFO - TEST-INFO | started process 3512 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBL9W4N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
16:54:09 INFO - PID 3512 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
16:54:09 INFO - PID 3512 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
16:54:09 INFO - PID 3512 |
16:54:09 INFO - PID 3512 | (/builds/slave/test/build/application/firefox/firefox:3573): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:54:09 INFO - PID 3512 |
16:54:11 INFO - PID 3512 | [talos glvideo result] Mean tick time across 100 ticks: 8.40925 ms
16:54:11 INFO - PID 3512 | Cycle 1(1): loaded http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html)
16:54:12 INFO - PID 3512 | [talos glvideo result] Mean tick time across 100 ticks: 6.4537 ms
16:54:12 INFO - PID 3512 | Cycle 1(2): loaded http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html)
16:54:13 INFO - PID 3512 | [talos glvideo result] Mean tick time across 100 ticks: 6.5127999999999995 ms
16:54:13 INFO - PID 3512 | Cycle 1(3): loaded http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html)
16:54:15 INFO - PID 3512 | [talos glvideo result] Mean tick time across 100 ticks: 6.6875 ms
16:54:15 INFO - PID 3512 | Cycle 1(4): loaded http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html)
16:54:16 INFO - PID 3512 | [talos glvideo result] Mean tick time across 100 ticks: 6.6057500000000005 ms
16:54:16 INFO - PID 3512 | Cycle 1(5): loaded http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51755/tests/webgl/benchmarks/video/video_upload.html)
16:54:16 INFO - PID 3512 | __start_tp_report
16:54:16 INFO - PID 3512 | _x_x_mozilla_page_load
16:54:16 INFO - PID 3512 | _x_x_mozilla_page_load_details
16:54:16 INFO - PID 3512 | |i|pagename|runs|
16:54:16 INFO - PID 3512 | |0;Mean tick time across 100 ticks: ;8.40925;6.4537;6.5127999999999995;6.6875;6.6057500000000005
16:54:16 INFO - PID 3512 | __end_tp_report
16:54:16 INFO - PID 3512 | __start_cc_report
16:54:16 INFO - PID 3512 | _x_x_mozilla_cycle_collect,301
16:54:16 INFO - PID 3512 | __end_cc_report
16:54:16 INFO - PID 3512 | __startTimestamp1511312056806__endTimestamp
16:54:16 INFO - PID 3512 |
16:54:16 INFO - PID 3512 | ------- Summary: start -------
16:54:16 INFO - PID 3512 | Number of tests: 1
16:54:16 INFO - PID 3512 |
16:54:16 INFO - PID 3512 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.93 Median:6.61 stddev:0.83 (12.6%) stddev-sans-first:0.10
16:54:16 INFO - PID 3512 | Values: 8.4 6.5 6.5 6.7 6.6
16:54:16 INFO - PID 3512 | -------- Summary: end --------
16:54:16 INFO - PID 3512 |
16:54:17 INFO - PID 3512 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
16:54:17 INFO - TEST-INFO | 3512: exit 0
16:54:17 INFO - TEST-OK | glvideo | took 15392ms
16:54:17 INFO - TEST-START | displaylist_mutate
16:54:17 INFO - Initialising browser for displaylist_mutate test...
16:54:17 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
16:54:17 INFO - Installing Add-ons
16:54:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51755/getInfo.html -profile /tmp/tmpd4LZz6/profile
16:54:17 INFO - TEST-INFO | started process 3746 (/builds/slave/test/build/application/firefox/firefox http://localhost:51755/getInfo.html)
16:54:23 INFO - TEST-INFO | 3746: exit 0
16:54:23 INFO - Browser initialized.
16:54:23 INFO - Running cycle 1/1 for displaylist_mutate test...
16:54:23 INFO - Using env: {'DISPLAY': ':0',
16:54:23 INFO - 'HOME': '/home/cltbld',
16:54:23 INFO - 'JSGC_DISABLE_POISONING': '1',
16:54:23 INFO - 'LANG': 'en_US.UTF-8',
16:54:23 INFO - 'LANGUAGE': 'en_US:en',
16:54:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:54:23 INFO - 'LOGNAME': 'cltbld',
16:54:23 INFO - 'MAIL': '/var/mail/cltbld',
16:54:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:54:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:54:23 INFO - 'MOZ_ACCELERATED': '1',
16:54:23 INFO - 'MOZ_CRASHREPORTER': '1',
16:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:54:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:54:23 INFO - 'MOZ_NO_REMOTE': '1',
16:54:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:54:23 INFO - 'MOZ_WEBRENDER': '1',
16:54:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:54:23 INFO - 'NO_EM_RESTART': '1',
16:54:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:54:23 INFO - 'PWD': '/builds/slave/test',
16:54:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:54:23 INFO - 'RUST_BACKTRACE': 'full',
16:54:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:54:23 INFO - 'SHELL': '/bin/bash',
16:54:23 INFO - 'SHLVL': '1',
16:54:23 INFO - 'TERM': 'linux',
16:54:23 INFO - 'TMOUT': '86400',
16:54:23 INFO - 'USER': 'cltbld',
16:54:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604',
16:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:54:23 INFO - '_': '/tools/buildbot/bin/python'}
16:54:23 INFO - TEST-INFO | started process 4024 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd4LZz6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
16:54:24 INFO - PID 4024 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
16:54:25 INFO - PID 4024 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
16:54:25 INFO - PID 4024 |
16:54:25 INFO - PID 4024 | (/builds/slave/test/build/application/firefox/firefox:4086): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:54:25 INFO - PID 4024 |
16:54:32 INFO - PID 4024 | Cycle 1(1): loaded http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html)
16:54:39 INFO - PID 4024 | Cycle 1(2): loaded http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html)
16:54:46 INFO - PID 4024 | Cycle 1(3): loaded http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html)
16:54:54 INFO - PID 4024 | Cycle 1(4): loaded http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html)
16:55:01 INFO - PID 4024 | Cycle 1(5): loaded http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:51755/tests/layout/benchmarks/displaylist_mutate.html)
16:55:01 INFO - PID 4024 | __start_tp_report
16:55:01 INFO - PID 4024 | _x_x_mozilla_page_load
16:55:01 INFO - PID 4024 | _x_x_mozilla_page_load_details
16:55:01 INFO - PID 4024 | |i|pagename|runs|
16:55:01 INFO - PID 4024 | |0;/displaylist_mutate.html;6420.42;6522.799999999999;6531.585;6549.46;6596.88
16:55:01 INFO - PID 4024 | __end_tp_report
16:55:01 INFO - PID 4024 | __start_cc_report
16:55:01 INFO - PID 4024 | _x_x_mozilla_cycle_collect,267
16:55:01 INFO - PID 4024 | __end_cc_report
16:55:01 INFO - PID 4024 | __startTimestamp1511312101924__endTimestamp
16:55:01 INFO - PID 4024 |
16:55:01 INFO - PID 4024 | ------- Summary: start -------
16:55:01 INFO - PID 4024 | Number of tests: 1
16:55:01 INFO - PID 4024 |
16:55:01 INFO - PID 4024 | [#0] /displaylist_mutate.html Cycles:5 Average:6524.23 Median:6531.59 stddev:64.71 (1.0%) stddev-sans-first:33.05
16:55:01 INFO - PID 4024 | Values: 6420.4 6522.8 6531.6 6549.5 6596.9
16:55:01 INFO - PID 4024 | -------- Summary: end --------
16:55:01 INFO - PID 4024 |
16:55:02 INFO - PID 4024 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
16:55:02 INFO - TEST-INFO | 4024: exit 0
16:55:02 INFO - TEST-OK | displaylist_mutate | took 45038ms
16:55:02 INFO - SUITE-END | took 620s
16:55:02 INFO - Completed test suite (00:10:21)
16:55:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.404756179396111, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.5257954545454546, 2.4940440565253534, 2.5317763713080166, 2.485509527754764, 2.4837003311258283, 2.48001652892562, 2.4797561983471077, 2.498526228143214, 2.4859527754763877, 2.488097014925373, 2.479880165289256, 2.481976840363937], "value": 2.485509527754764, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.460313653136532, 2.4608302583025834, 2.469956790123457, 2.457082309582309, 2.4603382533825338, 2.4609102091020914, 2.463245073891626, 2.4552576687116563, 2.463676108374385, 2.466430332922319, 2.4572542997543003, 2.4699197530864194], "value": 2.4609102091020914, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.5211302521008405, 2.510933054393306, 2.51684144295302, 2.511058577405858, 2.5174244966442956, 2.5086036789297657, 2.5106694560669456, 2.5191057934508816, 2.5128768844221105, 2.514945515507125, 2.5192989084802693, 2.5090677257525087], "value": 2.5128768844221105, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.498027465667914, 2.503554443053816, 2.5005812500000015, 2.5095545796737766, 2.5068859649122808, 2.5030913642052557, 2.5036170212765962, 2.5030225281602, 2.5010062500000014, 2.503153942428036, 2.504224030037547, 2.50129375], "value": 2.503153942428036, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.09358762886598, 3.0936649484536076, 3.089871266735326, 3.0804876796714566, 3.0801283367556467, 3.08355601233299, 3.0738012295081973, 3.080805954825462, 3.0778205128205127, 3.0810112936344978, 3.083895169578624, 3.077538461538462], "value": 3.080805954825462, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0637672281776407, 3.0770230769230777, 3.0637595712097982, 3.06362940275651, 3.0726804915514574, 3.0733717357910897, 3.067852760736196, 3.0726881720430104, 3.062848392036755, 3.0688726993865014, 3.077630769230768, 3.068880368098159], "value": 3.068880368098159, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.179094707520894, 4.196349650349649, 4.156011080332411, 4.525037707390652, 4.5675494672754935, 4.190858938547487, 4.16685416666667, 4.368326055312957, 4.406534508076361, 4.361642441860462, 4.237577683615819, 4.601756134969327], "value": 4.361642441860462, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.108049281314166, 4.099989754098365, 4.124216494845359, 4.476510067114094, 4.79685851318945, 4.098483606557379, 4.1340392561983474, 4.3310822510822495, 4.264413646055433, 4.32011879049676, 4.201995798319323, 4.619515011547344], "value": 4.264413646055433, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.525731523378584, 4.249893767705381, 4.498973013493257, 4.317071942446041, 4.406049926578562, 4.219810126582279, 4.225464788732392, 4.35537010159652, 4.552769347496208, 4.298918338108884, 4.412838235294117, 4.5319108761329305], "value": 4.35537010159652, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.54655681818182, 4.220263713080174, 4.496269662921349, 4.3479021739130435, 4.339360086767895, 4.20196428571429, 4.167958333333339, 4.256840425531915, 4.535759637188209, 4.203098739495797, 4.456481069042322, 4.4463777777777755], "value": 4.339360086767895, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.718498427672956, 4.78468102073365, 4.687914062500004, 4.688156249999997, 4.687796875000004, 4.652759689922479, 4.695790297339594, 4.807948717948718, 4.847487883683362, 4.703620689655172, 4.717845911949684, 4.687789062500002], "value": 4.695790297339594, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.673738317757011, 4.740699052132703, 4.66265734265734, 4.652569767441859, 4.651918604651156, 4.641044083526684, 4.653302325581391, 4.752280285035627, 4.773233890214798, 4.662890442890445, 4.66319347319347, 4.663566433566438], "value": 4.662890442890445, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.011880841121493, 7.026756440281028, 7.011471962616831, 6.994662004662011, 6.979127906976748, 7.011168224299071, 6.993764568764562, 6.994953379953378, 7.010245327102808, 7.011775700934573, 6.960661252900228, 6.995279720279717], "value": 6.995279720279717, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.780966101694922, 6.780966101694922, 6.850513698630132, 6.758868243243243, 6.757787162162163, 6.735572390572379, 6.826348122866903, 6.757922297297301, 6.75777027027028, 6.7803220338983285, 6.780525423728805, 6.849948630137003], "value": 6.7803220338983285, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.961519721577728, 7.076356132075472, 7.043579812206578, 7.010876168224296, 6.977406976744194, 6.982081395348833, 7.010724299065429, 7.03110070257611, 6.94435185185185, 6.97750000000001, 6.994673659673668, 7.026686182669799], "value": 7.010724299065429, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.7570439189189155, 6.803486394557818, 6.803962585034006, 6.803775510204098, 6.759459459459444, 6.735016835016845, 6.827969283276454, 6.781288135593207, 6.758868243243243, 6.781966101694891, 6.828532423208198, 6.758412162162156], "value": 6.781966101694891, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.027985948477745, 6.946076388888893, 6.998554778554789, 7.02655737704919, 7.010151869158881, 7.026030444964866, 6.993414918414925, 7.010385514018691, 6.977872093023257, 6.962273781902548, 6.9451736111111, 7.027388758782201], "value": 6.998554778554789, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.782593220339001, 6.734966329966317, 6.783033898305069, 6.781220338983048, 6.759898648648649, 6.803044217687079, 6.780084745762712, 6.7584797297297365, 6.804540816326543, 6.7834237288135615, 6.780084745762712, 6.78130508474574], "value": 6.781220338983048, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8.40925, 6.4537, 6.5127999999999995, 6.6875, 6.6057500000000005], "value": 6.5592749999999995, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6420.42, 6522.799999999999, 6531.585, 6549.46, 6596.88], "value": 6540.5225, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
16:55:02 INFO - Return code: 0
16:55:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
16:55:03 INFO - # TBPL SUCCESS #
16:55:03 INFO - Running post-action listener: _package_coverage_data
16:55:03 INFO - Running post-action listener: _resource_record_post_action
16:55:03 INFO - [mozharness: 2017-11-22 00:55:03.053809Z] Finished run-tests step (success)
16:55:03 INFO - Running post-run listener: _resource_record_post_run
16:55:03 INFO - Total resource usage - Wall time: 640s; CPU: 45.0%; Read bytes: 2826240; Write bytes: 339980288; Read time: 420; Write time: 279020
16:55:03 INFO - TinderboxPrint: CPU usage
45.1%
16:55:03 INFO - TinderboxPrint: I/O read bytes / time
2,826,240 / 420
16:55:03 INFO - TinderboxPrint: I/O write bytes / time
339,980,288 / 279,020
16:55:03 INFO - TinderboxPrint: CPU idle
2,610.6 (54.5%)
16:55:03 INFO - TinderboxPrint: CPU system
649.9 (13.6%)
16:55:03 INFO - TinderboxPrint: CPU user
1,510.2 (31.5%)
16:55:03 INFO - TinderboxPrint: Swap in / out
0 / 0
16:55:03 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5156864; Read time: 0; Write time: 300
16:55:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
16:55:03 INFO - run-tests - Wall time: 622s; CPU: 46.0%; Read bytes: 2826240; Write bytes: 334819328; Read time: 420; Write time: 278440
16:55:03 INFO - Running post-run listener: _upload_blobber_files
16:55:03 INFO - Blob upload gear active.
16:55:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
16:55:03 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
16:55:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
16:55:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
16:55:03 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
16:55:03 INFO - SNIMissingWarning
16:55:03 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:55:03 INFO - InsecurePlatformWarning
16:55:03 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.)
16:55:03 INFO - SubjectAltNameWarning
16:55:03 INFO - (blobuploader) - INFO - Open directory for files ...
16:55:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
16:55:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:55:03 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:55:03 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:55:03 INFO - InsecurePlatformWarning
16:55:04 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:55:04 INFO - SubjectAltNameWarning
16:55:04 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:55:04 INFO - InsecurePlatformWarning
16:55:04 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
16:55:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:55:04 INFO - (blobuploader) - INFO - Done attempting.
16:55:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
16:55:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:55:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:55:04 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:55:04 INFO - InsecurePlatformWarning
16:55:04 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:55:04 INFO - SubjectAltNameWarning
16:55:05 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
16:55:05 INFO - InsecurePlatformWarning
16:55:05 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
16:55:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:55:05 INFO - (blobuploader) - INFO - Done attempting.
16:55:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
16:55:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:55:05 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:55:05 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
16:55:05 INFO - InsecurePlatformWarning
16:55:05 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.)
16:55:05 INFO - SubjectAltNameWarning
16:55:07 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
16:55:07 INFO - InsecurePlatformWarning
16:55:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
16:55:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:55:08 INFO - (blobuploader) - INFO - Done attempting.
16:55:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
16:55:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:55:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:55:08 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
16:55:08 INFO - InsecurePlatformWarning
16:55:08 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.)
16:55:08 INFO - SubjectAltNameWarning
16:55:08 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
16:55:08 INFO - InsecurePlatformWarning
16:55:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
16:55:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:55:09 INFO - (blobuploader) - INFO - Done attempting.
16:55:09 INFO - (blobuploader) - INFO - Iteration through files over.
16:55:09 INFO - Return code: 0
16:55:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
16:55:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
16:55:09 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce1ca6212f8eb3fa93fdca19f493467ffd0dea00e143a6dbd348069deb28219d3ddc6ece4843d9e0904397b6e61dbf185096653c66434459a1c79adc316de20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb076958bfb5d73a3498ffb99613e8803634bda6198a4641e947910584997a558ef3648c2d1abe9e5c4ee5e5285e5b9a643695e87e674424c6fd11df324c818", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309249026be0e82b177ed595781b2cf154c09ada3818d52e4cef89e8594cf65ce3de0c62214995433e256a226999f5abaadead72c35704fe7c89c22782a74fa6", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5286430dae3a86fa100d50310834bd091a18e9dea7b7974651d11b0460dfc67127f6ea3b9df3d578593d1c471b22ac287c5da64037bff652574c79863422207"}
16:55:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
16:55:09 INFO - Writing to file /builds/slave/test/properties/blobber_files
16:55:09 INFO - Contents:
16:55:09 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce1ca6212f8eb3fa93fdca19f493467ffd0dea00e143a6dbd348069deb28219d3ddc6ece4843d9e0904397b6e61dbf185096653c66434459a1c79adc316de20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb076958bfb5d73a3498ffb99613e8803634bda6198a4641e947910584997a558ef3648c2d1abe9e5c4ee5e5285e5b9a643695e87e674424c6fd11df324c818", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309249026be0e82b177ed595781b2cf154c09ada3818d52e4cef89e8594cf65ce3de0c62214995433e256a226999f5abaadead72c35704fe7c89c22782a74fa6", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5286430dae3a86fa100d50310834bd091a18e9dea7b7974651d11b0460dfc67127f6ea3b9df3d578593d1c471b22ac287c5da64037bff652574c79863422207"}
16:55:09 INFO - Running post-run listener: copy_logs_to_upload_dir
16:55:09 INFO - Copying logs to upload dir...
16:55:09 INFO - mkdir: /builds/slave/test/build/upload/logs
16:55:09 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=699.779674
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 39 secs) (at 2017-11-21 16:55:09.151672) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-21 16:55:09.155049) =========
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=db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce1ca6212f8eb3fa93fdca19f493467ffd0dea00e143a6dbd348069deb28219d3ddc6ece4843d9e0904397b6e61dbf185096653c66434459a1c79adc316de20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb076958bfb5d73a3498ffb99613e8803634bda6198a4641e947910584997a558ef3648c2d1abe9e5c4ee5e5285e5b9a643695e87e674424c6fd11df324c818", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309249026be0e82b177ed595781b2cf154c09ada3818d52e4cef89e8594cf65ce3de0c62214995433e256a226999f5abaadead72c35704fe7c89c22782a74fa6", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5286430dae3a86fa100d50310834bd091a18e9dea7b7974651d11b0460dfc67127f6ea3b9df3d578593d1c471b22ac287c5da64037bff652574c79863422207"}
build_url:https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011434
build_url: 'https://queue.taskcluster.net/v1/task/P2MZIH96Qi-wy-ag3pkitw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1ce1ca6212f8eb3fa93fdca19f493467ffd0dea00e143a6dbd348069deb28219d3ddc6ece4843d9e0904397b6e61dbf185096653c66434459a1c79adc316de20", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bb076958bfb5d73a3498ffb99613e8803634bda6198a4641e947910584997a558ef3648c2d1abe9e5c4ee5e5285e5b9a643695e87e674424c6fd11df324c818", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/309249026be0e82b177ed595781b2cf154c09ada3818d52e4cef89e8594cf65ce3de0c62214995433e256a226999f5abaadead72c35704fe7c89c22782a74fa6", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c5286430dae3a86fa100d50310834bd091a18e9dea7b7974651d11b0460dfc67127f6ea3b9df3d578593d1c471b22ac287c5da64037bff652574c79863422207"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-21 16:55:09.185348) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-21 16:55:09.185794) =========
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=db0483c52d987ecb441a263b000001ad-1511311407.76831-1685023604
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004504
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-21 16:55:09.267483) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-21 16:55:09.267797) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-21 16:55:09.268101) =========
========= Total master_lag: 0.20 =========