builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-010
starttime: 1509737109.16
results: success (0)
revision: 66f496680fae6e7d8f02bc17ff58b9234ee07c70
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.158587) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.158985) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.159249) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.216605) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.217033) =========
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=eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-03 12:25:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 54.2M=0s
2017-11-03 12:25:09 (54.2 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.144533
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.386038) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.386308) =========
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=eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.022356
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:09.435560) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-03 12:25:09.435880) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 66f496680fae6e7d8f02bc17ff58b9234ee07c70 --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 66f496680fae6e7d8f02bc17ff58b9234ee07c70 --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=eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-03 12:25:09,495 truncating revision to first 12 chars
2017-11-03 12:25:09,495 Setting DEBUG logging.
2017-11-03 12:25:09,495 attempt 1/10
2017-11-03 12:25:09,495 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/66f496680fae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-03 12:25:10,076 unpacking tar archive at: mozilla-central-66f496680fae/testing/mozharness/
program finished with exit code 0
elapsedTime=1.161652
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-03 12:25:10.610479) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:10.610813) =========
script_repo_revision: 66f496680fae6e7d8f02bc17ff58b9234ee07c70
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:10.611311) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:10.611584) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-03 12:25:10.624158) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 47 secs) (at 2017-11-03 12:25:10.624503) =========
/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=eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
12:25:10 INFO - MultiFileLogger online at 20171103 12:25:10 in /builds/slave/test
12:25:10 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
12:25:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
12:25:10 INFO - {'append_to_log': False,
12:25:10 INFO - 'base_work_dir': '/builds/slave/test',
12:25:10 INFO - 'blob_upload_branch': 'Firefox',
12:25:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
12:25:10 INFO - 'branch': 'Firefox',
12:25:10 INFO - 'buildbot_json_path': 'buildprops.json',
12:25:10 INFO - 'code_coverage': False,
12:25:10 INFO - 'config_files': ('talos/linux_config.py',),
12:25:10 INFO - 'default_actions': ('clobber',
12:25:10 INFO - 'read-buildbot-config',
12:25:10 INFO - 'download-and-extract',
12:25:10 INFO - 'populate-webroot',
12:25:10 INFO - 'create-virtualenv',
12:25:10 INFO - 'install',
12:25:10 INFO - 'setup-mitmproxy',
12:25:10 INFO - 'run-tests'),
12:25:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:25:10 INFO - 'disable_ccov_upload': False,
12:25:10 INFO - 'disable_stylo': False,
12:25:10 INFO - 'download_minidump_stackwalk': True,
12:25:10 INFO - 'download_symbols': 'ondemand',
12:25:10 INFO - 'e10s': False,
12:25:10 INFO - 'enable_stylo': False,
12:25:10 INFO - 'enable_webrender': False,
12:25:10 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
12:25:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:25:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:25:10 INFO - 'gecko_profile': False,
12:25:10 INFO - 'gecko_profile_interval': 0,
12:25:10 INFO - 'installer_path': 'installer.exe',
12:25:10 INFO - 'jsd_code_coverage': False,
12:25:10 INFO - 'log_level': 'info',
12:25:10 INFO - 'log_name': 'talos',
12:25:10 INFO - 'log_to_console': True,
12:25:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
12:25:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
12:25:10 INFO - 'opt_config_files': (),
12:25:10 INFO - 'pip_index': False,
12:25:10 INFO - 'suite': 'g4-e10s',
12:25:10 INFO - 'system_bits': '32',
12:25:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:25:10 INFO - 'title': 'talos-linux64-ix-010',
12:25:10 INFO - 'tooltool_cache': '/builds/tooltool_cache',
12:25:10 INFO - 'use_talos_json': True,
12:25:10 INFO - 'verify': 'False',
12:25:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
12:25:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:25:10 INFO - 'work_dir': 'build'}
12:25:10 INFO - [mozharness: 2017-11-03 19:25:10.823617Z] Running clobber step.
12:25:10 INFO - Running pre-action listener: _resource_record_pre_action
12:25:10 INFO - Running main action method: clobber
12:25:10 INFO - rmtree: /builds/slave/test/build
12:25:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
12:25:11 INFO - Running post-action listener: _resource_record_post_action
12:25:11 INFO - [mozharness: 2017-11-03 19:25:11.900809Z] Finished clobber step (success)
12:25:11 INFO - [mozharness: 2017-11-03 19:25:11.900905Z] Running read-buildbot-config step.
12:25:11 INFO - Running pre-action listener: _resource_record_pre_action
12:25:11 INFO - Running main action method: read_buildbot_config
12:25:11 INFO - Using buildbot properties:
12:25:11 INFO - {
12:25:11 INFO - "project": "",
12:25:11 INFO - "product": "firefox",
12:25:11 INFO - "who": "ffxbld@noreply.mozilla.org",
12:25:11 INFO - "installer_path": "public/build/target.tar.bz2",
12:25:11 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
12:25:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
12:25:11 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
12:25:11 INFO - "stage_platform": "linux64-qr",
12:25:11 INFO - "basedir": "/builds/slave/test",
12:25:11 INFO - "buildnumber": 66,
12:25:11 INFO - "platform": "ubuntu64_hw_qr",
12:25:11 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
12:25:11 INFO - "slavebuilddir": "test",
12:25:11 INFO - "taskId": "aBTZWam8Riux-hsHknq_Pg",
12:25:11 INFO - "branch": "mozilla-central",
12:25:11 INFO - "script_repo_revision": "production",
12:25:11 INFO - "revision": "66f496680fae6e7d8f02bc17ff58b9234ee07c70",
12:25:11 INFO - "slavename": "talos-linux64-ix-010",
12:25:11 INFO - "repo_path": "mozilla-central"
12:25:11 INFO - }
12:25:11 INFO - Finding installer, test and symbols from parent task.
12:25:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aBTZWam8Riux-hsHknq_Pg'}, attempt #1
12:25:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aBTZWam8Riux-hsHknq_Pg'}, attempt #1
12:25:12 INFO - Task dependencies: BcGxGX9rQiKbWEKLwyfPww
12:25:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww'}, attempt #1
12:25:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2
12:25:13 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.test_packages.json
12:25:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.crashreporter-symbols.zip
12:25:13 INFO - Running post-action listener: _resource_record_post_action
12:25:13 INFO - [mozharness: 2017-11-03 19:25:13.465298Z] Finished read-buildbot-config step (success)
12:25:13 INFO - [mozharness: 2017-11-03 19:25:13.465516Z] Running download-and-extract step.
12:25:13 INFO - Running pre-action listener: _resource_record_pre_action
12:25:13 INFO - Running main action method: download_and_extract
12:25:13 INFO - mkdir: /builds/slave/test/build/tests
12:25:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:25:13 INFO - trying https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.test_packages.json
12:25:13 INFO - Downloading https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
12:25:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
12:25:14 INFO - Downloaded 989 bytes.
12:25:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
12:25:14 INFO - Using the following test package requirements:
12:25:14 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
12:25:14 INFO - u'common': [u'target.common.tests.zip'],
12:25:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
12:25:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
12:25:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
12:25:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
12:25:14 INFO - u'mozbase': [u'target.common.tests.zip'],
12:25:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
12:25:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
12:25:14 INFO - u'web-platform': [u'target.common.tests.zip',
12:25:14 INFO - u'target.web-platform.tests.tar.gz'],
12:25:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
12:25:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
12:25:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.common.tests.zip
12:25:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.common.tests.zip'}, attempt #1
12:25:14 INFO - Fetch https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.common.tests.zip into memory
12:25:15 INFO - Content-Length response header: 56205725
12:25:15 INFO - Bytes received: 56205725
12:25:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.talos.tests.zip
12:25:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.talos.tests.zip'}, attempt #1
12:25:26 INFO - Fetch https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.talos.tests.zip into memory
12:25:26 INFO - Content-Length response header: 18771919
12:25:26 INFO - Bytes received: 18771919
12:25:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:25:27 INFO - trying https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2
12:25:27 INFO - Downloading https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
12:25:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
12:25:28 INFO - Downloaded 62298700 bytes.
12:25:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2
12:25:28 INFO - mkdir: /builds/slave/test/properties
12:25:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
12:25:28 INFO - Writing to file /builds/slave/test/properties/build_url
12:25:28 INFO - Contents:
12:25:28 INFO - build_url:https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2
12:25:28 INFO - Running post-action listener: _resource_record_post_action
12:25:28 INFO - Running post-action listener: find_tests_for_verification
12:25:28 INFO - Running post-action listener: set_extra_try_arguments
12:25:28 INFO - [mozharness: 2017-11-03 19:25:28.760458Z] Finished download-and-extract step (success)
12:25:28 INFO - [mozharness: 2017-11-03 19:25:28.760565Z] Running populate-webroot step.
12:25:28 INFO - Running pre-action listener: _resource_record_pre_action
12:25:28 INFO - Running main action method: populate_webroot
12:25:28 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
12:25:28 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['tresize',
12:25:28 INFO - 'tcanvasmark']},
12:25:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
12:25:28 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['dromaeo_css',
12:25:28 INFO - 'kraken']},
12:25:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:25:28 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:25:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'tests': ['damp', 'tps']},
12:25:28 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['damp', 'tps']},
12:25:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
12:25:28 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['dromaeo_dom']},
12:25:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
12:25:28 INFO - 'glvideo',
12:25:28 INFO - 'displaylist_mutate']},
12:25:28 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['basic_compositor_video',
12:25:28 INFO - 'glvideo']},
12:25:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
12:25:28 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['ts_paint_webext',
12:25:28 INFO - 'tp5o_webext']},
12:25:28 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
12:25:28 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:25:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:25:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:25:28 INFO - 'talos_options': ['--mitmproxy',
12:25:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:25:28 INFO - '--firstNonBlankPaint'],
12:25:28 INFO - 'tests': ['tp6_google_heavy',
12:25:28 INFO - 'tp6_youtube_heavy',
12:25:28 INFO - 'tp6_amazon_heavy',
12:25:28 INFO - 'tp6_facebook_heavy']},
12:25:28 INFO - 'other-e10s': {'tests': ['a11yr',
12:25:28 INFO - 'ts_paint',
12:25:28 INFO - 'tpaint',
12:25:28 INFO - 'sessionrestore',
12:25:28 INFO - 'sessionrestore_many_windows',
12:25:28 INFO - 'sessionrestore_no_auto_restore',
12:25:28 INFO - 'tabpaint',
12:25:28 INFO - 'cpstartup']},
12:25:28 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['a11yr',
12:25:28 INFO - 'ts_paint',
12:25:28 INFO - 'tpaint',
12:25:28 INFO - 'sessionrestore',
12:25:28 INFO - 'sessionrestore_many_windows',
12:25:28 INFO - 'sessionrestore_no_auto_restore',
12:25:28 INFO - 'tabpaint',
12:25:28 INFO - 'cpstartup']},
12:25:28 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
12:25:28 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
12:25:28 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['perf_reftest_singletons']},
12:25:28 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['perf_reftest']},
12:25:28 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
12:25:28 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['speedometer']},
12:25:28 INFO - 'svgr-e10s': {'tests': ['tsvgx',
12:25:28 INFO - 'tsvgr_opacity',
12:25:28 INFO - 'tart',
12:25:28 INFO - 'tscrollx',
12:25:28 INFO - 'tsvg_static']},
12:25:28 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['tsvgx',
12:25:28 INFO - 'tsvgr_opacity',
12:25:28 INFO - 'tart',
12:25:28 INFO - 'tscrollx',
12:25:28 INFO - 'tsvg_static']},
12:25:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
12:25:28 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'talos_options': ['--disable-stylo'],
12:25:28 INFO - 'tests': ['tp5o']},
12:25:28 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:25:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:25:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:25:28 INFO - 'talos_options': ['--mitmproxy',
12:25:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:25:28 INFO - '--firstNonBlankPaint'],
12:25:28 INFO - 'tests': ['tp6_google',
12:25:28 INFO - 'tp6_youtube',
12:25:28 INFO - 'tp6_amazon',
12:25:28 INFO - 'tp6_facebook']},
12:25:28 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:25:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:25:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:25:28 INFO - 'talos_options': ['--disable-stylo',
12:25:28 INFO - '--mitmproxy',
12:25:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:25:28 INFO - '--firstNonBlankPaint'],
12:25:28 INFO - 'tests': ['tp6_google',
12:25:28 INFO - 'tp6_youtube',
12:25:28 INFO - 'tp6_amazon',
12:25:28 INFO - 'tp6_facebook']},
12:25:28 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:25:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:25:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:25:28 INFO - 'talos_options': ['--stylo-threads=1',
12:25:28 INFO - '--mitmproxy',
12:25:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:25:28 INFO - '--firstNonBlankPaint'],
12:25:28 INFO - 'tests': ['tp6_google',
12:25:28 INFO - 'tp6_youtube',
12:25:28 INFO - 'tp6_amazon',
12:25:28 INFO - 'tp6_facebook']},
12:25:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'talos_options': ['--xperf_path',
12:25:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:25:28 INFO - 'tests': ['tp5n']},
12:25:28 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:25:28 INFO - 'talos_options': ['--disable-stylo',
12:25:28 INFO - '--xperf_path',
12:25:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:25:28 INFO - 'tests': ['tp5n']}}}
12:25:28 INFO - Running post-action listener: _resource_record_post_action
12:25:28 INFO - [mozharness: 2017-11-03 19:25:28.778984Z] Finished populate-webroot step (success)
12:25:28 INFO - [mozharness: 2017-11-03 19:25:28.779078Z] Running create-virtualenv step.
12:25:28 INFO - Running pre-action listener: _resource_record_pre_action
12:25:28 INFO - Running main action method: create_virtualenv
12:25:28 INFO - Creating virtualenv /builds/slave/test/build/venv
12:25:28 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
12:25:28 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
12:25:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
12:25:29 INFO - Using real prefix '/usr'
12:25:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python
12:25:29 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
12:25:31 INFO - Installing setuptools, pip, wheel...done.
12:25:31 INFO - Return code: 0
12:25:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
12:25:31 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')]}
12:25:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25:31 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')]}
12:25:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:31 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:31 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
12:25:31 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
12:25:31 INFO - Using env: {'DISPLAY': ':0',
12:25:31 INFO - 'HOME': '/home/cltbld',
12:25:31 INFO - 'LANG': 'en_US.UTF-8',
12:25:31 INFO - 'LANGUAGE': 'en_US:en',
12:25:31 INFO - 'LOGNAME': 'cltbld',
12:25:31 INFO - 'MAIL': '/var/mail/cltbld',
12:25:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:31 INFO - 'MOZ_NO_REMOTE': '1',
12:25:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:31 INFO - 'NO_EM_RESTART': '1',
12:25:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:31 INFO - 'PWD': '/builds/slave/test',
12:25:31 INFO - 'SHELL': '/bin/bash',
12:25:31 INFO - 'SHLVL': '1',
12:25:31 INFO - 'TERM': 'linux',
12:25:31 INFO - 'TMOUT': '86400',
12:25:31 INFO - 'USER': 'cltbld',
12:25:31 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:31 INFO - '_': '/tools/buildbot/bin/python'}
12:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
12:25:32 INFO - Return code: 0
12:25:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
12:25: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')]}
12:25:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25: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')]}
12:25:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
12:25:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
12:25:32 INFO - Using env: {'DISPLAY': ':0',
12:25:32 INFO - 'HOME': '/home/cltbld',
12:25:32 INFO - 'LANG': 'en_US.UTF-8',
12:25:32 INFO - 'LANGUAGE': 'en_US:en',
12:25:32 INFO - 'LOGNAME': 'cltbld',
12:25:32 INFO - 'MAIL': '/var/mail/cltbld',
12:25:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:32 INFO - 'MOZ_NO_REMOTE': '1',
12:25:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:32 INFO - 'NO_EM_RESTART': '1',
12:25:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:32 INFO - 'PWD': '/builds/slave/test',
12:25:32 INFO - 'SHELL': '/bin/bash',
12:25:32 INFO - 'SHLVL': '1',
12:25:32 INFO - 'TERM': 'linux',
12:25:32 INFO - 'TMOUT': '86400',
12:25:32 INFO - 'USER': 'cltbld',
12:25:32 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:32 INFO - '_': '/tools/buildbot/bin/python'}
12:25:32 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:32 INFO - Collecting psutil>=3.1.1
12:25:33 INFO - Installing collected packages: psutil
12:25:33 INFO - Successfully installed psutil-3.1.1
12:25:33 INFO - Return code: 0
12:25:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
12:25:33 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')]}
12:25:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25:33 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')]}
12:25:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:33 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:33 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
12:25:33 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
12:25:33 INFO - Using env: {'DISPLAY': ':0',
12:25:33 INFO - 'HOME': '/home/cltbld',
12:25:33 INFO - 'LANG': 'en_US.UTF-8',
12:25:33 INFO - 'LANGUAGE': 'en_US:en',
12:25:33 INFO - 'LOGNAME': 'cltbld',
12:25:33 INFO - 'MAIL': '/var/mail/cltbld',
12:25:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:33 INFO - 'MOZ_NO_REMOTE': '1',
12:25:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:33 INFO - 'NO_EM_RESTART': '1',
12:25:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:33 INFO - 'PWD': '/builds/slave/test',
12:25:33 INFO - 'SHELL': '/bin/bash',
12:25:33 INFO - 'SHLVL': '1',
12:25:33 INFO - 'TERM': 'linux',
12:25:33 INFO - 'TMOUT': '86400',
12:25:33 INFO - 'USER': 'cltbld',
12:25:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:33 INFO - '_': '/tools/buildbot/bin/python'}
12:25:34 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:34 INFO - Collecting mozsystemmonitor==0.3
12:25:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
12:25:35 INFO - Installing collected packages: mozsystemmonitor
12:25:35 INFO - Successfully installed mozsystemmonitor-0.3
12:25:35 INFO - Return code: 0
12:25:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
12:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:25:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:25:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
12:25:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
12:25:35 INFO - Using env: {'DISPLAY': ':0',
12:25:35 INFO - 'HOME': '/home/cltbld',
12:25:35 INFO - 'LANG': 'en_US.UTF-8',
12:25:35 INFO - 'LANGUAGE': 'en_US:en',
12:25:35 INFO - 'LOGNAME': 'cltbld',
12:25:35 INFO - 'MAIL': '/var/mail/cltbld',
12:25:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:35 INFO - 'MOZ_NO_REMOTE': '1',
12:25:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:35 INFO - 'NO_EM_RESTART': '1',
12:25:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:35 INFO - 'PWD': '/builds/slave/test',
12:25:35 INFO - 'SHELL': '/bin/bash',
12:25:35 INFO - 'SHLVL': '1',
12:25:35 INFO - 'TERM': 'linux',
12:25:35 INFO - 'TMOUT': '86400',
12:25:35 INFO - 'USER': 'cltbld',
12:25:35 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:35 INFO - '_': '/tools/buildbot/bin/python'}
12:25:35 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:35 INFO - Collecting jsonschema==2.5.1
12:25:36 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
12:25:36 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
12:25:37 INFO - Installing collected packages: functools32, jsonschema
12:25:39 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
12:25:39 INFO - Return code: 0
12:25:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
12:25:39 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')]}
12:25:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25:39 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')]}
12:25:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:39 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:39 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
12:25:39 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
12:25:39 INFO - Using env: {'DISPLAY': ':0',
12:25:39 INFO - 'HOME': '/home/cltbld',
12:25:39 INFO - 'LANG': 'en_US.UTF-8',
12:25:39 INFO - 'LANGUAGE': 'en_US:en',
12:25:39 INFO - 'LOGNAME': 'cltbld',
12:25:39 INFO - 'MAIL': '/var/mail/cltbld',
12:25:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:39 INFO - 'MOZ_NO_REMOTE': '1',
12:25:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:39 INFO - 'NO_EM_RESTART': '1',
12:25:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:39 INFO - 'PWD': '/builds/slave/test',
12:25:39 INFO - 'SHELL': '/bin/bash',
12:25:39 INFO - 'SHLVL': '1',
12:25:39 INFO - 'TERM': 'linux',
12:25:39 INFO - 'TMOUT': '86400',
12:25:39 INFO - 'USER': 'cltbld',
12:25:39 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:39 INFO - '_': '/tools/buildbot/bin/python'}
12:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
12:25:40 INFO - Return code: 0
12:25:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
12:25:40 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')]}
12:25:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25:40 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')]}
12:25:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:40 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:40 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
12:25:40 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
12:25:40 INFO - Using env: {'DISPLAY': ':0',
12:25:40 INFO - 'HOME': '/home/cltbld',
12:25:40 INFO - 'LANG': 'en_US.UTF-8',
12:25:40 INFO - 'LANGUAGE': 'en_US:en',
12:25:40 INFO - 'LOGNAME': 'cltbld',
12:25:40 INFO - 'MAIL': '/var/mail/cltbld',
12:25:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:40 INFO - 'MOZ_NO_REMOTE': '1',
12:25:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:40 INFO - 'NO_EM_RESTART': '1',
12:25:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:40 INFO - 'PWD': '/builds/slave/test',
12:25:40 INFO - 'SHELL': '/bin/bash',
12:25:40 INFO - 'SHLVL': '1',
12:25:40 INFO - 'TERM': 'linux',
12:25:40 INFO - 'TMOUT': '86400',
12:25:40 INFO - 'USER': 'cltbld',
12:25:40 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:40 INFO - '_': '/tools/buildbot/bin/python'}
12:25:40 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:40 INFO - Collecting blobuploader==1.2.4
12:25:41 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
12:25:42 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
12:25:43 INFO - Installing collected packages: requests, docopt, blobuploader
12:25:43 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
12:25:43 INFO - Return code: 0
12:25:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:25:43 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')]}
12:25:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25:43 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')]}
12:25:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:43 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:43 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
12:25:43 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
12:25:43 INFO - Using env: {'DISPLAY': ':0',
12:25:43 INFO - 'HOME': '/home/cltbld',
12:25:43 INFO - 'LANG': 'en_US.UTF-8',
12:25:43 INFO - 'LANGUAGE': 'en_US:en',
12:25:43 INFO - 'LOGNAME': 'cltbld',
12:25:43 INFO - 'MAIL': '/var/mail/cltbld',
12:25:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:43 INFO - 'MOZ_NO_REMOTE': '1',
12:25:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:43 INFO - 'NO_EM_RESTART': '1',
12:25:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:43 INFO - 'PWD': '/builds/slave/test',
12:25:43 INFO - 'SHELL': '/bin/bash',
12:25:43 INFO - 'SHLVL': '1',
12:25:43 INFO - 'TERM': 'linux',
12:25:43 INFO - 'TMOUT': '86400',
12:25:43 INFO - 'USER': 'cltbld',
12:25:43 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:43 INFO - '_': '/tools/buildbot/bin/python'}
12:25:44 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:44 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:25:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:25:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:25:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:25:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:25:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:25:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:25:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:25:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:25:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:25:46 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:25:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:25:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:25:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:25:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:25:47 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:25:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:25:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:25:47 INFO - Running setup.py install for manifestparser: started
12:25:47 INFO - Running setup.py install for manifestparser: finished with status 'done'
12:25:47 INFO - Running setup.py install for mozcrash: started
12:25:48 INFO - Running setup.py install for mozcrash: finished with status 'done'
12:25:48 INFO - Running setup.py install for mozdebug: started
12:25:48 INFO - Running setup.py install for mozdebug: finished with status 'done'
12:25:48 INFO - Running setup.py install for mozdevice: started
12:25:48 INFO - Running setup.py install for mozdevice: finished with status 'done'
12:25:48 INFO - Running setup.py install for mozfile: started
12:25:48 INFO - Running setup.py install for mozfile: finished with status 'done'
12:25:48 INFO - Running setup.py install for mozhttpd: started
12:25:48 INFO - Running setup.py install for mozhttpd: finished with status 'done'
12:25:48 INFO - Running setup.py install for mozinfo: started
12:25:49 INFO - Running setup.py install for mozinfo: finished with status 'done'
12:25:49 INFO - Running setup.py install for mozInstall: started
12:25:49 INFO - Running setup.py install for mozInstall: finished with status 'done'
12:25:49 INFO - Running setup.py install for mozleak: started
12:25:49 INFO - Running setup.py install for mozleak: finished with status 'done'
12:25:49 INFO - Running setup.py install for mozlog: started
12:25:49 INFO - Running setup.py install for mozlog: finished with status 'done'
12:25:49 INFO - Running setup.py install for moznetwork: started
12:25:50 INFO - Running setup.py install for moznetwork: finished with status 'done'
12:25:50 INFO - Running setup.py install for mozprocess: started
12:25:50 INFO - Running setup.py install for mozprocess: finished with status 'done'
12:25:50 INFO - Running setup.py install for mozprofile: started
12:25:50 INFO - Running setup.py install for mozprofile: finished with status 'done'
12:25:50 INFO - Running setup.py install for mozrunner: started
12:25:50 INFO - Running setup.py install for mozrunner: finished with status 'done'
12:25:50 INFO - Running setup.py install for mozscreenshot: started
12:25:50 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
12:25:50 INFO - Running setup.py install for moztest: started
12:25:51 INFO - Running setup.py install for moztest: finished with status 'done'
12:25:51 INFO - Running setup.py install for mozversion: started
12:25:51 INFO - Running setup.py install for mozversion: finished with status 'done'
12:25:51 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
12:25:51 INFO - Return code: 0
12:25:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:25: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')]}
12:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25: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')]}
12:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:51 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:51 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
12:25:51 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
12:25:51 INFO - Using env: {'DISPLAY': ':0',
12:25:51 INFO - 'HOME': '/home/cltbld',
12:25:51 INFO - 'LANG': 'en_US.UTF-8',
12:25:51 INFO - 'LANGUAGE': 'en_US:en',
12:25:51 INFO - 'LOGNAME': 'cltbld',
12:25:51 INFO - 'MAIL': '/var/mail/cltbld',
12:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:51 INFO - 'MOZ_NO_REMOTE': '1',
12:25:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:51 INFO - 'NO_EM_RESTART': '1',
12:25:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:51 INFO - 'PWD': '/builds/slave/test',
12:25:51 INFO - 'SHELL': '/bin/bash',
12:25:51 INFO - 'SHLVL': '1',
12:25:51 INFO - 'TERM': 'linux',
12:25:51 INFO - 'TMOUT': '86400',
12:25:51 INFO - 'USER': 'cltbld',
12:25:51 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:51 INFO - '_': '/tools/buildbot/bin/python'}
12:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:52 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:25:52 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))
12:25:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:25:52 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))
12:25:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:25:52 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))
12:25:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:25:52 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))
12:25:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:25:53 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))
12:25:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:25:53 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))
12:25:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:25:53 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))
12:25:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:25:53 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))
12:25:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:25:53 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))
12:25:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:25:54 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))
12:25:54 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:25:54 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))
12:25:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:25:54 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))
12:25:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:25:54 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))
12:25:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:25:55 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))
12:25:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:25:55 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))
12:25:55 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:25:55 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))
12:25:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:25:55 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))
12:25:55 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:25:56 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
12:25:56 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))
12:25:56 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))
12:25:56 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))
12:25:56 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))
12:25:56 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))
12:25:56 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))
12:25:56 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:25:57 INFO - Installing collected packages: six, blessings
12:25:57 INFO - Successfully installed blessings-1.6 six-1.10.0
12:25:57 INFO - Return code: 0
12:25:57 INFO - Done creating virtualenv /builds/slave/test/build/venv.
12:25:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:25:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:25:58 INFO - Reading from file tmpfile_stdout
12:25:58 INFO - Reading from file tmpfile_stderr
12:25:58 INFO - Current package versions:
12:25:58 INFO - blessings == 1.6
12:25:58 INFO - blobuploader == 1.2.4
12:25:58 INFO - docopt == 0.6.1
12:25:58 INFO - functools32 == 3.2.3.post2
12:25:58 INFO - jsonschema == 2.5.1
12:25:58 INFO - manifestparser == 1.2
12:25:58 INFO - mozInstall == 1.14
12:25:58 INFO - mozcrash == 1.0
12:25:58 INFO - mozdebug == 0.1
12:25:58 INFO - mozdevice == 0.51
12:25:58 INFO - mozfile == 1.2
12:25:58 INFO - mozhttpd == 0.7
12:25:58 INFO - mozinfo == 0.10
12:25:58 INFO - mozleak == 0.1
12:25:58 INFO - mozlog == 3.5
12:25:58 INFO - moznetwork == 0.27
12:25:58 INFO - mozprocess == 0.25
12:25:58 INFO - mozprofile == 0.29
12:25:58 INFO - mozrunner == 6.13
12:25:58 INFO - mozscreenshot == 0.1
12:25:58 INFO - mozsystemmonitor == 0.3
12:25:58 INFO - moztest == 0.8
12:25:58 INFO - mozversion == 1.4
12:25:58 INFO - psutil == 3.1.1
12:25:58 INFO - requests == 1.2.3
12:25:58 INFO - six == 1.10.0
12:25:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:25:58 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')]}
12:25:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:25:58 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')]}
12:25:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:25:58 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:25:58 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
12:25:58 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
12:25:58 INFO - Using env: {'DISPLAY': ':0',
12:25:58 INFO - 'HOME': '/home/cltbld',
12:25:58 INFO - 'LANG': 'en_US.UTF-8',
12:25:58 INFO - 'LANGUAGE': 'en_US:en',
12:25:58 INFO - 'LOGNAME': 'cltbld',
12:25:58 INFO - 'MAIL': '/var/mail/cltbld',
12:25:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:58 INFO - 'MOZ_NO_REMOTE': '1',
12:25:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:58 INFO - 'NO_EM_RESTART': '1',
12:25:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:58 INFO - 'PWD': '/builds/slave/test',
12:25:58 INFO - 'SHELL': '/bin/bash',
12:25:58 INFO - 'SHLVL': '1',
12:25:58 INFO - 'TERM': 'linux',
12:25:58 INFO - 'TMOUT': '86400',
12:25:58 INFO - 'USER': 'cltbld',
12:25:58 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:25:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:58 INFO - '_': '/tools/buildbot/bin/python'}
12:25:58 INFO - Ignoring indexes: https://pypi.python.org/simple
12:25:58 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))
12:25:58 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))
12:25:58 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))
12:25:58 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))
12:25:58 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))
12:25:58 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))
12:25:58 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))
12:25:58 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))
12:25:58 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))
12:25:58 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
12:25:59 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:26:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
12:26:01 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))
12:26:01 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))
12:26:01 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))
12:26:01 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))
12:26:02 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
12:26:02 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:26:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
12:26:03 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:26:04 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
12:26:04 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:26:05 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
12:26:05 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
12:26:05 INFO - Found existing installation: requests 1.2.3
12:26:05 INFO - Uninstalling requests-1.2.3:
12:26:05 INFO - Successfully uninstalled requests-1.2.3
12:26:05 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
12:26:05 INFO - Return code: 0
12:26:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
12:26:05 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')]}
12:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:26:05 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')]}
12:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:26:05 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 0x1e6b2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2045a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21e4d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, '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': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756', '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
12:26:05 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
12:26:05 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
12:26:05 INFO - Using env: {'DISPLAY': ':0',
12:26:05 INFO - 'HOME': '/home/cltbld',
12:26:05 INFO - 'LANG': 'en_US.UTF-8',
12:26:05 INFO - 'LANGUAGE': 'en_US:en',
12:26:05 INFO - 'LOGNAME': 'cltbld',
12:26:05 INFO - 'MAIL': '/var/mail/cltbld',
12:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:26:05 INFO - 'MOZ_NO_REMOTE': '1',
12:26:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:26:05 INFO - 'NO_EM_RESTART': '1',
12:26:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:26:05 INFO - 'PWD': '/builds/slave/test',
12:26:05 INFO - 'SHELL': '/bin/bash',
12:26:05 INFO - 'SHLVL': '1',
12:26:05 INFO - 'TERM': 'linux',
12:26:05 INFO - 'TMOUT': '86400',
12:26:05 INFO - 'USER': 'cltbld',
12:26:05 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:26:05 INFO - '_': '/tools/buildbot/bin/python'}
12:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple
12:26:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
12:26:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
12:26:06 INFO - Return code: 0
12:26:06 INFO - Running post-action listener: _resource_record_post_action
12:26:06 INFO - Running post-action listener: _start_resource_monitoring
12:26:06 INFO - Starting resource monitoring.
12:26:06 INFO - [mozharness: 2017-11-03 19:26:06.250204Z] Finished create-virtualenv step (success)
12:26:06 INFO - [mozharness: 2017-11-03 19:26:06.250716Z] Running install step.
12:26:06 INFO - Running pre-action listener: _resource_record_pre_action
12:26:06 INFO - Running main action method: install
12:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:26:06 INFO - Reading from file tmpfile_stdout
12:26:06 INFO - Reading from file tmpfile_stderr
12:26:06 INFO - Detecting whether we're running mozinstall >=1.0...
12:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
12:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
12:26:06 INFO - Reading from file tmpfile_stdout
12:26:06 INFO - Output received:
12:26:06 INFO - Usage: mozinstall [options] installer
12:26:06 INFO - Options:
12:26:06 INFO - -h, --help show this help message and exit
12:26:06 INFO - -d DEST, --destination=DEST
12:26:06 INFO - Directory to install application into. [default:
12:26:06 INFO - "/builds/slave/test"]
12:26:06 INFO - --app=APP Application being installed. [default: firefox]
12:26:06 INFO - mkdir: /builds/slave/test/build/application
12:26:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
12:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
12:26:25 INFO - Reading from file tmpfile_stdout
12:26:25 INFO - Output received:
12:26:25 INFO - /builds/slave/test/build/application/firefox/firefox
12:26:25 INFO - Running post-action listener: _resource_record_post_action
12:26:25 INFO - [mozharness: 2017-11-03 19:26:25.248951Z] Finished install step (success)
12:26:25 INFO - [mozharness: 2017-11-03 19:26:25.249249Z] Running setup-mitmproxy step.
12:26:25 INFO - Running pre-action listener: _resource_record_pre_action
12:26:25 INFO - Running main action method: setup_mitmproxy
12:26:25 INFO - Skipping: mitmproxy is not required
12:26:25 INFO - Running post-action listener: _resource_record_post_action
12:26:25 INFO - [mozharness: 2017-11-03 19:26:25.250350Z] Finished setup-mitmproxy step (success)
12:26:25 INFO - [mozharness: 2017-11-03 19:26:25.250565Z] Running run-tests step.
12:26:25 INFO - Running pre-action listener: _resource_record_pre_action
12:26:25 INFO - Running pre-action listener: _set_gcov_prefix
12:26:25 INFO - Running main action method: run_tests
12:26:25 WARNING - Try message not found.
12:26:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
12:26:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
12:26:25 INFO - Python 2.7.3
12:26:25 INFO - Return code: 0
12:26:25 INFO - grabbing minidump binary from tooltool
12:26:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:26:25 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 0x21e8070>, '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 0x21e9080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e32a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
12:26:25 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
12:26:25 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
12:26:25 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
12:26:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
12:26:25 INFO - Return code: 0
12:26:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
12:26:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
12:26:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
12:26:25 INFO - ENV: RUST_BACKTRACE is now full
12:26:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
12:26:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
12:26:25 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/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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
12:26:25 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/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-010 --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
12:26:25 INFO - Using env: {'DISPLAY': ':0',
12:26:25 INFO - 'HOME': '/home/cltbld',
12:26:25 INFO - 'LANG': 'en_US.UTF-8',
12:26:25 INFO - 'LANGUAGE': 'en_US:en',
12:26:25 INFO - 'LOGNAME': 'cltbld',
12:26:25 INFO - 'MAIL': '/var/mail/cltbld',
12:26:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:26:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:26:25 INFO - 'MOZ_ACCELERATED': '1',
12:26:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:26:25 INFO - 'MOZ_NO_REMOTE': '1',
12:26:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:26:25 INFO - 'MOZ_WEBRENDER': '1',
12:26:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:26:25 INFO - 'NO_EM_RESTART': '1',
12:26:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:26:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:26:25 INFO - 'PWD': '/builds/slave/test',
12:26:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:26:25 INFO - 'RUST_BACKTRACE': 'full',
12:26:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:26:25 INFO - 'SHELL': '/bin/bash',
12:26:25 INFO - 'SHLVL': '1',
12:26:25 INFO - 'TERM': 'linux',
12:26:25 INFO - 'TMOUT': '86400',
12:26:25 INFO - 'USER': 'cltbld',
12:26:25 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:26:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:26:25 INFO - '_': '/tools/buildbot/bin/python'}
12:26:25 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/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-010', '--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
12:26:25 INFO - mozversion application_buildid: 20171103183339
12:26:25 INFO - mozversion application_changeset: 66f496680fae6e7d8f02bc17ff58b9234ee07c70
12:26:25 INFO - mozversion application_display_name: Nightly
12:26:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:26:25 INFO - mozversion application_name: Firefox
12:26:25 INFO - mozversion application_remotingname: firefox
12:26:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
12:26:25 INFO - mozversion application_vendor: Mozilla
12:26:25 INFO - mozversion application_version: 58.0a1
12:26:25 INFO - mozversion platform_buildid: 20171103183339
12:26:25 INFO - mozversion platform_changeset: 66f496680fae6e7d8f02bc17ff58b9234ee07c70
12:26:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
12:26:25 INFO - mozversion platform_version: 58.0a1
12:26:25 INFO - using testdate: 1509737185
12:26:25 INFO - actual date: 1509737185
12:26:25 INFO - starting webserver on 'localhost:57614'
12:26:25 INFO - SUITE-START | Running 3 tests
12:26:25 INFO - TEST-START | basic_compositor_video
12:26:25 INFO - Initialising browser for basic_compositor_video test...
12:26:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57614/getInfo.html -profile /tmp/tmpThZswD/profile
12:26:25 INFO - TEST-INFO | started process 13955 (/builds/slave/test/build/application/firefox/firefox http://localhost:57614/getInfo.html)
12:26:33 INFO - TEST-INFO | 13955: exit 0
12:26:33 INFO - Browser initialized.
12:26:33 INFO - Running cycle 1/1 for basic_compositor_video test...
12:26:33 INFO - Using env: {'DISPLAY': ':0',
12:26:33 INFO - 'HOME': '/home/cltbld',
12:26:33 INFO - 'JSGC_DISABLE_POISONING': '1',
12:26:33 INFO - 'LANG': 'en_US.UTF-8',
12:26:33 INFO - 'LANGUAGE': 'en_US:en',
12:26:33 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:26:33 INFO - 'LOGNAME': 'cltbld',
12:26:33 INFO - 'MAIL': '/var/mail/cltbld',
12:26:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:26:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:26:33 INFO - 'MOZ_ACCELERATED': '1',
12:26:33 INFO - 'MOZ_CRASHREPORTER': '1',
12:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:26:33 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:26:33 INFO - 'MOZ_NO_REMOTE': '1',
12:26:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:26:33 INFO - 'MOZ_WEBRENDER': '1',
12:26:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:26:33 INFO - 'NO_EM_RESTART': '1',
12:26:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:26:33 INFO - 'PWD': '/builds/slave/test',
12:26:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:26:33 INFO - 'RUST_BACKTRACE': 'full',
12:26:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:26:33 INFO - 'SHELL': '/bin/bash',
12:26:33 INFO - 'SHLVL': '1',
12:26:33 INFO - 'TERM': 'linux',
12:26:33 INFO - 'TMOUT': '86400',
12:26:33 INFO - 'USER': 'cltbld',
12:26:33 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:26:33 INFO - '_': '/tools/buildbot/bin/python'}
12:26:33 INFO - TEST-INFO | started process 14200 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpThZswD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
12:26:34 INFO - PID 14200 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:26:34 INFO - PID 14200 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:26:34 INFO - PID 14200 |
12:26:34 INFO - PID 14200 | (/builds/slave/test/build/application/firefox/firefox:14262): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:26:34 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6936759425493717 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4580466830466823 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5084197324414714 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.497403245942572 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0489126016260153 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0446803652968057 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.178948467966575 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.186119246861925 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.286978571428569 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.238718220338981 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.847156704361873 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.773054892601432 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.960638051044075 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.735909090909098 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.914308755760363 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.692107023411379 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.994009324009324 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.714781879194622 ms/frame
12:27:21 INFO - PID 14200 |
12:27:21 INFO - PID 14200 | Cycle 1(1): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:28:07 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.473849958779885 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4459168704156475 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.513333333333333 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4887375621890553 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.055982688391039 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.039848024316111 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.311091954022986 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.237055084745761 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.178474930362118 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.074164969450104 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.658563664596276 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.618741339491919 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.94479166666667 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781694915254232 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.97781395348837 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758868243243243 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.97729069767442 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.735404040404044 ms/frame
12:28:07 INFO - PID 14200 |
12:28:07 INFO - PID 14200 | Cycle 1(2): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:28:53 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4861019055509526 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4543926380368095 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4920639534883726 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.487873134328358 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.043468559837729 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0444901065449006 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.1963706293706275 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.176085594989564 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.250389518413595 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.159407484407483 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.762182539682539 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.774260143198092 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.913640552995393 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.714530201342283 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.961774941995363 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.733804713804721 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.009205607476641 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.736818181818179 ms/frame
12:28:53 INFO - PID 14200 |
12:28:53 INFO - PID 14200 | Cycle 1(3): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:29:39 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.469164609053498 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.452352941176471 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4920847176079737 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.491251556662516 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0521108850457788 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0359484066767837 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.202661064425769 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.167718750000002 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.237612994350282 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.221170886075948 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.666337480559872 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.630393518518523 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.009462616822428 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.756976351351359 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9453125 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.759070945945963 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.898402298850575 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.712164429530197 ms/frame
12:29:39 INFO - PID 14200 |
12:29:39 INFO - PID 14200 | Cycle 1(4): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:30:25 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.47951652892562 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.449167686658507 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.502381150959133 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.495548628428927 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0525890132248215 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0445509893455105 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.190495810055866 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.2207700421940935 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.36140261627907 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.274529914529913 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.824220257234728 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.763190476190478 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.946041666666672 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.713875838926176 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.913940092165902 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.691939799331101 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.944305555555544 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.758462837837854 ms/frame
12:30:25 INFO - PID 14200 |
12:30:25 INFO - PID 14200 | Cycle 1(5): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:31:11 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.459795081967213 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4485495716034267 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5091889632107023 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.491313823163139 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.061607142857143 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.035933232169955 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.267894736842103 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.168125000000002 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.214382022471909 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.175146137787057 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.800640000000002 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.753289786223274 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.930623556581993 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.6923076923076925 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.930000000000005 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.711929530201338 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.930762124711309 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.757989864864882 ms/frame
12:31:11 INFO - PID 14200 |
12:31:11 INFO - PID 14200 | Cycle 1(6): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:31:35 INFO - PID 14200 |
12:31:35 INFO - PID 14200 | (/builds/slave/test/build/application/firefox/firefox:14344): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:31:35 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4631978653530378 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.442808302808303 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4980432972522895 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4886007462686566 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0429361054766724 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0363201820940837 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.710133437990581 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.786949760765549 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.191284916201116 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.149460580912865 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.681216848673947 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.599264367816093 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.995337995337987 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.734865319865335 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9617053364269035 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7798474576271195 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.960394431554525 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.713204697986565 ms/frame
12:31:57 INFO - PID 14200 |
12:31:57 INFO - PID 14200 | Cycle 1(7): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:32:43 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4735119538334707 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4727441285537703 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.519156171284634 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4852360248447214 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0525991861648016 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0450304414003058 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.717154088050313 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.774988066825778 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.532922960725078 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.515857787810379 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.658944099378883 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.587775229357799 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.896977011494248 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.689397993311053 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.944606481481481 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.736397306397303 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.8972183908046 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.690953177257529 ms/frame
12:32:43 INFO - PID 14200 |
12:32:43 INFO - PID 14200 | Cycle 1(8): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:33:29 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4639121510673236 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.454877300613497 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.491798172757475 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4849316770186345 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.046142131979696 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.036115326251896 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.115953360768174 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.0092885771543125 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.219613220815753 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.18428870292887 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.732452681388013 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.630196759259265 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.945393518518518 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.690133779264209 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.882970183486235 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.690150501672251 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.009953271028042 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.689531772575245 ms/frame
12:33:29 INFO - PID 14200 |
12:33:29 INFO - PID 14200 | Cycle 1(9): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:34:15 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.490070539419087 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4458801955990226 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.504954090150251 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4945698254364097 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0467157360406087 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.044954337899546 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.4715946348733215 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.72793144208038 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.322845821325648 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.239152542372883 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.710847723704869 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.707905882352943 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.944594907407413 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.73409090909089 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.930669745958437 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.714714765100673 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.944942129629622 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.666783333333345 ms/frame
12:34:15 INFO - PID 14200 |
12:34:15 INFO - PID 14200 | Cycle 1(10): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:35:01 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.455531914893617 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.457862407862408 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.504674457429049 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4888308457711448 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0525788402848417 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.044916286149163 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.220105485232066 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.176179540709811 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.298194842406876 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.2294503171247335 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.718097484276728 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.706094117647059 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.97882558139535 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.712567114093964 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.948784722222222 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.69198996655518 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.976930232558144 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.736767676767676 ms/frame
12:35:01 INFO - PID 14200 |
12:35:01 INFO - PID 14200 | Cycle 1(11): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:35:47 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4633538587848935 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4543619631901845 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.498597002497919 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.491488169364883 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_startup = 3.046593908629442 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0400683890577502 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_startup = 4.033178763440859 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1_inclip = 4.238315677966103 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.553808801213959 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.536394557823128 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_startup = 4.703189655172417 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673925233644857 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.961415313225055 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.7394276094276115 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.91411290322582 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7585472972972935 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.881685779816515 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.667066666666651 ms/frame
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | Cycle 1(12): loaded http://localhost:57614/tests/video/video_playback.html (next: http://localhost:57614/tests/video/video_playback.html)
12:35:47 INFO - PID 14200 | __start_tp_report
12:35:47 INFO - PID 14200 | _x_x_mozilla_page_load
12:35:47 INFO - PID 14200 | _x_x_mozilla_page_load_details
12:35:47 INFO - PID 14200 | |i|pagename|runs|
12:35:47 INFO - PID 14200 | |0;240p.120fps.mp4_scale_1_startup;2.6936759425493717;2.473849958779885;2.4861019055509526;2.469164609053498;2.47951652892562;2.459795081967213;2.4631978653530378;2.4735119538334707;2.4639121510673236;2.490070539419087;2.455531914893617;2.4633538587848935
12:35:47 INFO - PID 14200 | |1;240p.120fps.mp4_scale_1_inclip;2.4580466830466823;2.4459168704156475;2.4543926380368095;2.452352941176471;2.449167686658507;2.4485495716034267;2.442808302808303;2.4727441285537703;2.454877300613497;2.4458801955990226;2.457862407862408;2.4543619631901845
12:35:47 INFO - PID 14200 | |2;240p.120fps.mp4_scale_1.1_startup;2.5084197324414714;2.513333333333333;2.4920639534883726;2.4920847176079737;2.502381150959133;2.5091889632107023;2.4980432972522895;2.519156171284634;2.491798172757475;2.504954090150251;2.504674457429049;2.498597002497919
12:35:47 INFO - PID 14200 | |3;240p.120fps.mp4_scale_1.1_inclip;2.497403245942572;2.4887375621890553;2.487873134328358;2.491251556662516;2.495548628428927;2.491313823163139;2.4886007462686566;2.4852360248447214;2.4849316770186345;2.4945698254364097;2.4888308457711448;2.491488169364883
12:35:47 INFO - PID 14200 | |4;240p.120fps.mp4_scale_2_startup;3.0489126016260153;3.055982688391039;3.043468559837729;3.0521108850457788;3.0525890132248215;3.061607142857143;3.0429361054766724;3.0525991861648016;3.046142131979696;3.0467157360406087;3.0525788402848417;3.046593908629442
12:35:47 INFO - PID 14200 | |5;240p.120fps.mp4_scale_2_inclip;3.0446803652968057;3.039848024316111;3.0444901065449006;3.0359484066767837;3.0445509893455105;3.035933232169955;3.0363201820940837;3.0450304414003058;3.036115326251896;3.044954337899546;3.044916286149163;3.0400683890577502
12:35:47 INFO - PID 14200 | |6;480p.60fps.webm_scale_1_startup;4.178948467966575;4.311091954022986;4.1963706293706275;4.202661064425769;4.190495810055866;4.267894736842103;4.710133437990581;4.717154088050313;4.115953360768174;4.4715946348733215;4.220105485232066;4.033178763440859
12:35:47 INFO - PID 14200 | |7;480p.60fps.webm_scale_1_inclip;4.186119246861925;4.237055084745761;4.176085594989564;4.167718750000002;4.2207700421940935;4.168125000000002;4.786949760765549;4.774988066825778;4.0092885771543125;4.72793144208038;4.176179540709811;4.238315677966103
12:35:47 INFO - PID 14200 | |8;480p.60fps.webm_scale_1.1_startup;4.286978571428569;4.178474930362118;4.250389518413595;4.237612994350282;4.36140261627907;4.214382022471909;4.191284916201116;4.532922960725078;4.219613220815753;4.322845821325648;4.298194842406876;4.553808801213959
12:35:47 INFO - PID 14200 | |9;480p.60fps.webm_scale_1.1_inclip;4.238718220338981;4.074164969450104;4.159407484407483;4.221170886075948;4.274529914529913;4.175146137787057;4.149460580912865;4.515857787810379;4.18428870292887;4.239152542372883;4.2294503171247335;4.536394557823128
12:35:47 INFO - PID 14200 | |10;480p.60fps.webm_scale_2_startup;4.847156704361873;4.658563664596276;4.762182539682539;4.666337480559872;4.824220257234728;4.800640000000002;4.681216848673947;4.658944099378883;4.732452681388013;4.710847723704869;4.718097484276728;4.703189655172417
12:35:47 INFO - PID 14200 | |11;480p.60fps.webm_scale_2_inclip;4.773054892601432;4.618741339491919;4.774260143198092;4.630393518518523;4.763190476190478;4.753289786223274;4.599264367816093;4.587775229357799;4.630196759259265;4.707905882352943;4.706094117647059;4.673925233644857
12:35:47 INFO - PID 14200 | |12;1080p.60fps.mp4_scale_1_startup;6.960638051044075;6.94479166666667;6.913640552995393;7.009462616822428;6.946041666666672;6.930623556581993;6.995337995337987;6.896977011494248;6.945393518518518;6.944594907407413;6.97882558139535;6.961415313225055
12:35:47 INFO - PID 14200 | |13;1080p.60fps.mp4_scale_1_inclip;6.735909090909098;6.781694915254232;6.714530201342283;6.756976351351359;6.713875838926176;6.6923076923076925;6.734865319865335;6.689397993311053;6.690133779264209;6.73409090909089;6.712567114093964;6.7394276094276115
12:35:47 INFO - PID 14200 | |14;1080p.60fps.mp4_scale_1.1_startup;6.914308755760363;6.97781395348837;6.961774941995363;6.9453125;6.913940092165902;6.930000000000005;6.9617053364269035;6.944606481481481;6.882970183486235;6.930669745958437;6.948784722222222;6.91411290322582
12:35:47 INFO - PID 14200 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.692107023411379;6.758868243243243;6.733804713804721;6.759070945945963;6.691939799331101;6.711929530201338;6.7798474576271195;6.736397306397303;6.690150501672251;6.714714765100673;6.69198996655518;6.7585472972972935
12:35:47 INFO - PID 14200 | |16;1080p.60fps.mp4_scale_2_startup;6.994009324009324;6.97729069767442;7.009205607476641;6.898402298850575;6.944305555555544;6.930762124711309;6.960394431554525;6.8972183908046;7.009953271028042;6.944942129629622;6.976930232558144;6.881685779816515
12:35:47 INFO - PID 14200 | |17;1080p.60fps.mp4_scale_2_inclip;6.714781879194622;6.735404040404044;6.736818181818179;6.712164429530197;6.758462837837854;6.757989864864882;6.713204697986565;6.690953177257529;6.689531772575245;6.666783333333345;6.736767676767676;6.667066666666651
12:35:47 INFO - PID 14200 | __end_tp_report
12:35:47 INFO - PID 14200 | __start_cc_report
12:35:47 INFO - PID 14200 | _x_x_mozilla_cycle_collect,626
12:35:47 INFO - PID 14200 | __end_cc_report
12:35:47 INFO - PID 14200 | __startTimestamp1509737747575__endTimestamp
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | ------- Summary: start -------
12:35:47 INFO - PID 14200 | Number of tests: 18
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.49 Median:2.47 stddev:0.07 (2.6%) stddev-sans-first:0.01
12:35:47 INFO - PID 14200 | Values: 2.7 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.45 Median:2.45 stddev:0.01 (0.3%) stddev-sans-first:0.01
12:35:47 INFO - PID 14200 | Values: 2.5 2.4 2.5 2.5 2.4 2.4 2.4 2.5 2.5 2.4 2.5 2.5
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.50 Median:2.50 stddev:0.01 (0.4%) stddev-sans-first:0.01
12:35:47 INFO - PID 14200 | 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
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.49 Median:2.49 stddev:0.00 (0.2%) stddev-sans-first:0.00
12:35:47 INFO - PID 14200 | 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
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.05 Median:3.05 stddev:0.01 (0.2%) stddev-sans-first:0.01
12:35:47 INFO - PID 14200 | Values: 3.0 3.1 3.0 3.1 3.1 3.1 3.0 3.1 3.0 3.0 3.1 3.0
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.04 Median:3.04 stddev:0.00 (0.1%) stddev-sans-first:0.00
12:35:47 INFO - PID 14200 | Values: 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.30 Median:4.24 stddev:0.22 (5.2%) stddev-sans-first:0.23
12:35:47 INFO - PID 14200 | Values: 4.2 4.3 4.2 4.2 4.2 4.3 4.7 4.7 4.1 4.5 4.2 4.0
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.32 Median:4.23 stddev:0.27 (6.4%) stddev-sans-first:0.28
12:35:47 INFO - PID 14200 | Values: 4.2 4.2 4.2 4.2 4.2 4.2 4.8 4.8 4.0 4.7 4.2 4.2
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.30 Median:4.29 stddev:0.12 (2.9%) stddev-sans-first:0.13
12:35:47 INFO - PID 14200 | Values: 4.3 4.2 4.3 4.2 4.4 4.2 4.2 4.5 4.2 4.3 4.3 4.6
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.25 Median:4.23 stddev:0.14 (3.3%) stddev-sans-first:0.15
12:35:47 INFO - PID 14200 | Values: 4.2 4.1 4.2 4.2 4.3 4.2 4.1 4.5 4.2 4.2 4.2 4.5
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.73 Median:4.73 stddev:0.06 (1.4%) stddev-sans-first:0.06
12:35:47 INFO - PID 14200 | Values: 4.8 4.7 4.8 4.7 4.8 4.8 4.7 4.7 4.7 4.7 4.7 4.7
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.68 Median:4.71 stddev:0.07 (1.5%) stddev-sans-first:0.07
12:35:47 INFO - PID 14200 | Values: 4.8 4.6 4.8 4.6 4.8 4.8 4.6 4.6 4.6 4.7 4.7 4.7
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:6.95 Median:6.95 stddev:0.03 (0.5%) stddev-sans-first:0.03
12:35:47 INFO - PID 14200 | Values: 7.0 6.9 6.9 7.0 6.9 6.9 7.0 6.9 6.9 6.9 7.0 7.0
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.72 Median:6.73 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:35:47 INFO - PID 14200 | Values: 6.7 6.8 6.7 6.8 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:6.94 Median:6.94 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:35:47 INFO - PID 14200 | Values: 6.9 7.0 7.0 6.9 6.9 6.9 7.0 6.9 6.9 6.9 6.9 6.9
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.73 Median:6.74 stddev:0.03 (0.5%) stddev-sans-first:0.03
12:35:47 INFO - PID 14200 | Values: 6.7 6.8 6.7 6.8 6.7 6.7 6.8 6.7 6.7 6.7 6.7 6.8
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:6.95 Median:6.97 stddev:0.04 (0.6%) stddev-sans-first:0.04
12:35:47 INFO - PID 14200 | Values: 7.0 7.0 7.0 6.9 6.9 6.9 7.0 6.9 7.0 6.9 7.0 6.9
12:35:47 INFO - PID 14200 |
12:35:47 INFO - PID 14200 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.71 Median:6.73 stddev:0.03 (0.5%) stddev-sans-first:0.03
12:35:47 INFO - PID 14200 | Values: 6.7 6.7 6.7 6.7 6.8 6.8 6.7 6.7 6.7 6.7 6.7 6.7
12:35:47 INFO - PID 14200 | -------- Summary: end --------
12:35:47 INFO - PID 14200 |
12:35:48 INFO - TEST-INFO | 14200: exit 0
12:35:48 INFO - TEST-OK | basic_compositor_video | took 562606ms
12:35:48 INFO - TEST-START | glvideo
12:35:48 INFO - Initialising browser for glvideo test...
12:35:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57614/getInfo.html -profile /tmp/tmp_bWZU_/profile
12:35:48 INFO - TEST-INFO | started process 15542 (/builds/slave/test/build/application/firefox/firefox http://localhost:57614/getInfo.html)
12:35:54 INFO - TEST-INFO | 15542: exit 0
12:35:54 INFO - Browser initialized.
12:35:54 INFO - Running cycle 1/1 for glvideo test...
12:35:54 INFO - Using env: {'DISPLAY': ':0',
12:35:54 INFO - 'HOME': '/home/cltbld',
12:35:54 INFO - 'JSGC_DISABLE_POISONING': '1',
12:35:54 INFO - 'LANG': 'en_US.UTF-8',
12:35:54 INFO - 'LANGUAGE': 'en_US:en',
12:35:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:35:54 INFO - 'LOGNAME': 'cltbld',
12:35:54 INFO - 'MAIL': '/var/mail/cltbld',
12:35:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:35:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:35:54 INFO - 'MOZ_ACCELERATED': '1',
12:35:54 INFO - 'MOZ_CRASHREPORTER': '1',
12:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:35:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:35:54 INFO - 'MOZ_NO_REMOTE': '1',
12:35:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:35:54 INFO - 'MOZ_WEBRENDER': '1',
12:35:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:35:54 INFO - 'NO_EM_RESTART': '1',
12:35:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:35:54 INFO - 'PWD': '/builds/slave/test',
12:35:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:35:54 INFO - 'RUST_BACKTRACE': 'full',
12:35:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:35:54 INFO - 'SHELL': '/bin/bash',
12:35:54 INFO - 'SHLVL': '1',
12:35:54 INFO - 'TERM': 'linux',
12:35:54 INFO - 'TMOUT': '86400',
12:35:54 INFO - 'USER': 'cltbld',
12:35:54 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:35:54 INFO - '_': '/tools/buildbot/bin/python'}
12:35:54 INFO - TEST-INFO | started process 15783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_bWZU_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:35:55 INFO - PID 15783 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:35:55 INFO - PID 15783 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:35:55 INFO - PID 15783 |
12:35:55 INFO - PID 15783 | (/builds/slave/test/build/application/firefox/firefox:15844): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:35:55 INFO - PID 15783 |
12:35:57 INFO - PID 15783 | [talos glvideo result] Mean tick time across 100 ticks: 6.795250000000001 ms
12:35:57 INFO - PID 15783 | Cycle 1(1): loaded http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html)
12:35:58 INFO - PID 15783 | [talos glvideo result] Mean tick time across 100 ticks: 6.41775 ms
12:35:58 INFO - PID 15783 | Cycle 1(2): loaded http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html)
12:36:00 INFO - PID 15783 | [talos glvideo result] Mean tick time across 100 ticks: 6.5619499999999995 ms
12:36:00 INFO - PID 15783 | Cycle 1(3): loaded http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html)
12:36:01 INFO - PID 15783 | [talos glvideo result] Mean tick time across 100 ticks: 6.67105 ms
12:36:01 INFO - PID 15783 | Cycle 1(4): loaded http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html)
12:36:02 INFO - PID 15783 | [talos glvideo result] Mean tick time across 100 ticks: 6.57395 ms
12:36:02 INFO - PID 15783 | Cycle 1(5): loaded http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:57614/tests/webgl/benchmarks/video/video_upload.html)
12:36:03 INFO - PID 15783 | __start_tp_report
12:36:03 INFO - PID 15783 | _x_x_mozilla_page_load
12:36:03 INFO - PID 15783 | _x_x_mozilla_page_load_details
12:36:03 INFO - PID 15783 | |i|pagename|runs|
12:36:03 INFO - PID 15783 | |0;Mean tick time across 100 ticks: ;6.795250000000001;6.41775;6.5619499999999995;6.67105;6.57395
12:36:03 INFO - PID 15783 | __end_tp_report
12:36:03 INFO - PID 15783 | __start_cc_report
12:36:03 INFO - PID 15783 | _x_x_mozilla_cycle_collect,291
12:36:03 INFO - PID 15783 | __end_cc_report
12:36:03 INFO - PID 15783 | __startTimestamp1509737763021__endTimestamp
12:36:03 INFO - PID 15783 |
12:36:03 INFO - PID 15783 | ------- Summary: start -------
12:36:03 INFO - PID 15783 | Number of tests: 1
12:36:03 INFO - PID 15783 |
12:36:03 INFO - PID 15783 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.60 Median:6.57 stddev:0.14 (2.1%) stddev-sans-first:0.10
12:36:03 INFO - PID 15783 | Values: 6.8 6.4 6.6 6.7 6.6
12:36:03 INFO - PID 15783 | -------- Summary: end --------
12:36:03 INFO - PID 15783 |
12:36:03 INFO - PID 15783 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:36:03 INFO - TEST-INFO | 15783: exit 0
12:36:03 INFO - TEST-OK | glvideo | took 15491ms
12:36:03 INFO - TEST-START | displaylist_mutate
12:36:03 INFO - Initialising browser for displaylist_mutate test...
12:36:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:57614/getInfo.html -profile /tmp/tmpKEpUuw/profile
12:36:03 INFO - TEST-INFO | started process 16014 (/builds/slave/test/build/application/firefox/firefox http://localhost:57614/getInfo.html)
12:36:11 INFO - TEST-INFO | 16014: exit 0
12:36:11 INFO - Browser initialized.
12:36:11 INFO - Running cycle 1/1 for displaylist_mutate test...
12:36:11 INFO - Using env: {'DISPLAY': ':0',
12:36:11 INFO - 'HOME': '/home/cltbld',
12:36:11 INFO - 'JSGC_DISABLE_POISONING': '1',
12:36:11 INFO - 'LANG': 'en_US.UTF-8',
12:36:11 INFO - 'LANGUAGE': 'en_US:en',
12:36:11 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:36:11 INFO - 'LOGNAME': 'cltbld',
12:36:11 INFO - 'MAIL': '/var/mail/cltbld',
12:36:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:36:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:36:11 INFO - 'MOZ_ACCELERATED': '1',
12:36:11 INFO - 'MOZ_CRASHREPORTER': '1',
12:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:36:11 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:36:11 INFO - 'MOZ_NO_REMOTE': '1',
12:36:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:36:11 INFO - 'MOZ_WEBRENDER': '1',
12:36:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:36:11 INFO - 'NO_EM_RESTART': '1',
12:36:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:36:11 INFO - 'PWD': '/builds/slave/test',
12:36:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:36:11 INFO - 'RUST_BACKTRACE': 'full',
12:36:11 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:36:11 INFO - 'SHELL': '/bin/bash',
12:36:11 INFO - 'SHLVL': '1',
12:36:11 INFO - 'TERM': 'linux',
12:36:11 INFO - 'TMOUT': '86400',
12:36:11 INFO - 'USER': 'cltbld',
12:36:11 INFO - 'XDG_SESSION_COOKIE': 'eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756',
12:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:36:11 INFO - '_': '/tools/buildbot/bin/python'}
12:36:11 INFO - TEST-INFO | started process 16297 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpKEpUuw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:36:11 INFO - PID 16297 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:36:12 INFO - PID 16297 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:36:12 INFO - PID 16297 |
12:36:12 INFO - PID 16297 | (/builds/slave/test/build/application/firefox/firefox:16359): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:36:12 INFO - PID 16297 |
12:36:30 INFO - PID 16297 | Cycle 1(1): loaded http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html)
12:36:31 INFO - PID 16297 | Waiting for idle-callback
12:36:50 INFO - PID 16297 | Cycle 1(2): loaded http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html)
12:36:50 INFO - PID 16297 | Waiting for idle-callback
12:37:09 INFO - PID 16297 | Cycle 1(3): loaded http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html)
12:37:28 INFO - PID 16297 | Cycle 1(4): loaded http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html)
12:37:47 INFO - PID 16297 | Cycle 1(5): loaded http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:57614/tests/layout/benchmarks/displaylist_mutate.html)
12:37:47 INFO - PID 16297 | __start_tp_report
12:37:47 INFO - PID 16297 | _x_x_mozilla_page_load
12:37:47 INFO - PID 16297 | _x_x_mozilla_page_load_details
12:37:47 INFO - PID 16297 | |i|pagename|runs|
12:37:47 INFO - PID 16297 | |0;/displaylist_mutate.html;17965.785;18437.775;18259.505;18158.960000000003;18093.125
12:37:47 INFO - PID 16297 | __end_tp_report
12:37:47 INFO - PID 16297 | __start_cc_report
12:37:47 INFO - PID 16297 | _x_x_mozilla_cycle_collect,217
12:37:47 INFO - PID 16297 | __end_cc_report
12:37:47 INFO - PID 16297 | __startTimestamp1509737867726__endTimestamp
12:37:47 INFO - PID 16297 |
12:37:47 INFO - PID 16297 | ------- Summary: start -------
12:37:47 INFO - PID 16297 | Number of tests: 1
12:37:47 INFO - PID 16297 |
12:37:47 INFO - PID 16297 | [#0] /displaylist_mutate.html Cycles:5 Average:18183.03 Median:18158.96 stddev:177.91 (1.0%) stddev-sans-first:150.12
12:37:47 INFO - PID 16297 | Values: 17965.8 18437.8 18259.5 18159.0 18093.1
12:37:47 INFO - PID 16297 | -------- Summary: end --------
12:37:47 INFO - PID 16297 |
12:37:48 INFO - TEST-INFO | 16297: exit 0
12:37:48 INFO - TEST-OK | displaylist_mutate | took 104638ms
12:37:48 INFO - SUITE-END | took 682s
12:37:48 INFO - Completed test suite (00:11:23)
12:37:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.361138853229377, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.6936759425493717, 2.473849958779885, 2.4861019055509526, 2.469164609053498, 2.47951652892562, 2.459795081967213, 2.4631978653530378, 2.4735119538334707, 2.4639121510673236, 2.490070539419087, 2.455531914893617, 2.4633538587848935], "value": 2.469164609053498, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4580466830466823, 2.4459168704156475, 2.4543926380368095, 2.452352941176471, 2.449167686658507, 2.4485495716034267, 2.442808302808303, 2.4727441285537703, 2.454877300613497, 2.4458801955990226, 2.457862407862408, 2.4543619631901845], "value": 2.452352941176471, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.5084197324414714, 2.513333333333333, 2.4920639534883726, 2.4920847176079737, 2.502381150959133, 2.5091889632107023, 2.4980432972522895, 2.519156171284634, 2.491798172757475, 2.504954090150251, 2.504674457429049, 2.498597002497919], "value": 2.502381150959133, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.497403245942572, 2.4887375621890553, 2.487873134328358, 2.491251556662516, 2.495548628428927, 2.491313823163139, 2.4886007462686566, 2.4852360248447214, 2.4849316770186345, 2.4945698254364097, 2.4888308457711448, 2.491488169364883], "value": 2.4888308457711448, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0489126016260153, 3.055982688391039, 3.043468559837729, 3.0521108850457788, 3.0525890132248215, 3.061607142857143, 3.0429361054766724, 3.0525991861648016, 3.046142131979696, 3.0467157360406087, 3.0525788402848417, 3.046593908629442], "value": 3.0521108850457788, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0446803652968057, 3.039848024316111, 3.0444901065449006, 3.0359484066767837, 3.0445509893455105, 3.035933232169955, 3.0363201820940837, 3.0450304414003058, 3.036115326251896, 3.044954337899546, 3.044916286149163, 3.0400683890577502], "value": 3.0400683890577502, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.178948467966575, 4.311091954022986, 4.1963706293706275, 4.202661064425769, 4.190495810055866, 4.267894736842103, 4.710133437990581, 4.717154088050313, 4.115953360768174, 4.4715946348733215, 4.220105485232066, 4.033178763440859], "value": 4.220105485232066, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.186119246861925, 4.237055084745761, 4.176085594989564, 4.167718750000002, 4.2207700421940935, 4.168125000000002, 4.786949760765549, 4.774988066825778, 4.0092885771543125, 4.72793144208038, 4.176179540709811, 4.238315677966103], "value": 4.2207700421940935, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.286978571428569, 4.178474930362118, 4.250389518413595, 4.237612994350282, 4.36140261627907, 4.214382022471909, 4.191284916201116, 4.532922960725078, 4.219613220815753, 4.322845821325648, 4.298194842406876, 4.553808801213959], "value": 4.250389518413595, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.238718220338981, 4.074164969450104, 4.159407484407483, 4.221170886075948, 4.274529914529913, 4.175146137787057, 4.149460580912865, 4.515857787810379, 4.18428870292887, 4.239152542372883, 4.2294503171247335, 4.536394557823128], "value": 4.221170886075948, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.847156704361873, 4.658563664596276, 4.762182539682539, 4.666337480559872, 4.824220257234728, 4.800640000000002, 4.681216848673947, 4.658944099378883, 4.732452681388013, 4.710847723704869, 4.718097484276728, 4.703189655172417], "value": 4.710847723704869, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.773054892601432, 4.618741339491919, 4.774260143198092, 4.630393518518523, 4.763190476190478, 4.753289786223274, 4.599264367816093, 4.587775229357799, 4.630196759259265, 4.707905882352943, 4.706094117647059, 4.673925233644857], "value": 4.673925233644857, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.960638051044075, 6.94479166666667, 6.913640552995393, 7.009462616822428, 6.946041666666672, 6.930623556581993, 6.995337995337987, 6.896977011494248, 6.945393518518518, 6.944594907407413, 6.97882558139535, 6.961415313225055], "value": 6.945393518518518, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.735909090909098, 6.781694915254232, 6.714530201342283, 6.756976351351359, 6.713875838926176, 6.6923076923076925, 6.734865319865335, 6.689397993311053, 6.690133779264209, 6.73409090909089, 6.712567114093964, 6.7394276094276115], "value": 6.714530201342283, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.914308755760363, 6.97781395348837, 6.961774941995363, 6.9453125, 6.913940092165902, 6.930000000000005, 6.9617053364269035, 6.944606481481481, 6.882970183486235, 6.930669745958437, 6.948784722222222, 6.91411290322582], "value": 6.944606481481481, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.692107023411379, 6.758868243243243, 6.733804713804721, 6.759070945945963, 6.691939799331101, 6.711929530201338, 6.7798474576271195, 6.736397306397303, 6.690150501672251, 6.714714765100673, 6.69198996655518, 6.7585472972972935], "value": 6.733804713804721, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.994009324009324, 6.97729069767442, 7.009205607476641, 6.898402298850575, 6.944305555555544, 6.930762124711309, 6.960394431554525, 6.8972183908046, 7.009953271028042, 6.944942129629622, 6.976930232558144, 6.881685779816515], "value": 6.944942129629622, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.714781879194622, 6.735404040404044, 6.736818181818179, 6.712164429530197, 6.758462837837854, 6.757989864864882, 6.713204697986565, 6.690953177257529, 6.689531772575245, 6.666783333333345, 6.736767676767676, 6.667066666666651], "value": 6.713204697986565, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.795250000000001, 6.41775, 6.5619499999999995, 6.67105, 6.57395], "value": 6.56795, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [17965.785, 18437.775, 18259.505, 18158.960000000003, 18093.125], "value": 18209.232500000002, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
12:37:48 INFO - Return code: 0
12:37:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
12:37:48 INFO - # TBPL SUCCESS #
12:37:48 INFO - Running post-action listener: _package_coverage_data
12:37:48 INFO - Running post-action listener: _resource_record_post_action
12:37:48 INFO - [mozharness: 2017-11-03 19:37:48.887837Z] Finished run-tests step (success)
12:37:48 INFO - Running post-run listener: _resource_record_post_run
12:37:48 INFO - Total resource usage - Wall time: 702s; CPU: 42.0%; Read bytes: 5967872; Write bytes: 349986816; Read time: 3580; Write time: 345456
12:37:48 INFO - TinderboxPrint: CPU usage
42.3%
12:37:48 INFO - TinderboxPrint: I/O read bytes / time
5,967,872 / 3,580
12:37:48 INFO - TinderboxPrint: I/O write bytes / time
349,986,816 / 345,456
12:37:48 INFO - TinderboxPrint: CPU idle
3,045.9 (57.5%)
12:37:48 INFO - TinderboxPrint: CPU system
650.7 (12.3%)
12:37:48 INFO - TinderboxPrint: CPU user
1,574.3 (29.7%)
12:37:48 INFO - TinderboxPrint: Swap in / out
0 / 0
12:37:48 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 6176768; Read time: 0; Write time: 828
12:37:48 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
12:37:48 INFO - run-tests - Wall time: 684s; CPU: 43.0%; Read bytes: 5967872; Write bytes: 343810048; Read time: 3580; Write time: 344628
12:37:49 INFO - Running post-run listener: _upload_blobber_files
12:37:49 INFO - Blob upload gear active.
12:37:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
12:37:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
12:37:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
12:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
12:37:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:49 INFO - SNIMissingWarning
12:37:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:49 INFO - InsecurePlatformWarning
12:37:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:37:49 INFO - SubjectAltNameWarning
12:37:49 INFO - (blobuploader) - INFO - Open directory for files ...
12:37:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
12:37:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:37:49 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:37:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:49 INFO - InsecurePlatformWarning
12:37:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:37:49 INFO - SubjectAltNameWarning
12:37:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:50 INFO - InsecurePlatformWarning
12:37:50 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
12:37:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:37:50 INFO - (blobuploader) - INFO - Done attempting.
12:37:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
12:37:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:37:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:37:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:50 INFO - InsecurePlatformWarning
12:37:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:37:50 INFO - SubjectAltNameWarning
12:37:51 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:51 INFO - InsecurePlatformWarning
12:37:51 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
12:37:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:37:51 INFO - (blobuploader) - INFO - Done attempting.
12:37:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
12:37:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:37:51 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:37:51 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:51 INFO - InsecurePlatformWarning
12:37:51 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:37:51 INFO - SubjectAltNameWarning
12:37:56 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:56 INFO - InsecurePlatformWarning
12:37:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:37:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:37:56 INFO - (blobuploader) - INFO - Done attempting.
12:37:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
12:37:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:37:56 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:37:56 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:56 INFO - InsecurePlatformWarning
12:37:57 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:37:57 INFO - SubjectAltNameWarning
12:37:57 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:37:57 INFO - InsecurePlatformWarning
12:37:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
12:37:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:37:57 INFO - (blobuploader) - INFO - Done attempting.
12:37:57 INFO - (blobuploader) - INFO - Iteration through files over.
12:37:57 INFO - Return code: 0
12:37:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
12:37:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
12:37:57 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4667370be234b5edb5f2f2e30165a86ecf0441d51669939649becdae7c887fa897a26eb6e43ffe550936ce03087d5ed62ac7035f378021b9e644b5b87f82e702", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9def35f39fb7c6429f24994bc2aac0d4413d8d0f3705061f9e77e0c8a5c633871fdc39685e51f1e6c40dcf960e5cd045bb4b99d566a60ef1c2467a0653a75a51", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04dec58fe538938da59b1e072cfbd82576affb0161ce3a3c10e7f51e6d61baedba72b375925c4859b7bfa8b4ab3fda7e80aca2ffdd892e75512bb3dc2d210e43", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1a43534518a1e149666a968d70dbcff73e807ccbaeac8e3f6412a577ada331920dbe516e7802255a0ed601d2684d6c38d525a911a3642c574c2b1ecaeccd02"}
12:37:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
12:37:57 INFO - Writing to file /builds/slave/test/properties/blobber_files
12:37:57 INFO - Contents:
12:37:57 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4667370be234b5edb5f2f2e30165a86ecf0441d51669939649becdae7c887fa897a26eb6e43ffe550936ce03087d5ed62ac7035f378021b9e644b5b87f82e702", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9def35f39fb7c6429f24994bc2aac0d4413d8d0f3705061f9e77e0c8a5c633871fdc39685e51f1e6c40dcf960e5cd045bb4b99d566a60ef1c2467a0653a75a51", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04dec58fe538938da59b1e072cfbd82576affb0161ce3a3c10e7f51e6d61baedba72b375925c4859b7bfa8b4ab3fda7e80aca2ffdd892e75512bb3dc2d210e43", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1a43534518a1e149666a968d70dbcff73e807ccbaeac8e3f6412a577ada331920dbe516e7802255a0ed601d2684d6c38d525a911a3642c574c2b1ecaeccd02"}
12:37:57 INFO - Running post-run listener: copy_logs_to_upload_dir
12:37:57 INFO - Copying logs to upload dir...
12:37:57 INFO - mkdir: /builds/slave/test/build/upload/logs
12:37:57 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=767.041576
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 47 secs) (at 2017-11-03 12:37:57.697951) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-03 12:37:57.701353) =========
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=eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756
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/4667370be234b5edb5f2f2e30165a86ecf0441d51669939649becdae7c887fa897a26eb6e43ffe550936ce03087d5ed62ac7035f378021b9e644b5b87f82e702", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9def35f39fb7c6429f24994bc2aac0d4413d8d0f3705061f9e77e0c8a5c633871fdc39685e51f1e6c40dcf960e5cd045bb4b99d566a60ef1c2467a0653a75a51", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04dec58fe538938da59b1e072cfbd82576affb0161ce3a3c10e7f51e6d61baedba72b375925c4859b7bfa8b4ab3fda7e80aca2ffdd892e75512bb3dc2d210e43", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1a43534518a1e149666a968d70dbcff73e807ccbaeac8e3f6412a577ada331920dbe516e7802255a0ed601d2684d6c38d525a911a3642c574c2b1ecaeccd02"}
build_url:https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011302
build_url: 'https://queue.taskcluster.net/v1/task/BcGxGX9rQiKbWEKLwyfPww/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4667370be234b5edb5f2f2e30165a86ecf0441d51669939649becdae7c887fa897a26eb6e43ffe550936ce03087d5ed62ac7035f378021b9e644b5b87f82e702", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9def35f39fb7c6429f24994bc2aac0d4413d8d0f3705061f9e77e0c8a5c633871fdc39685e51f1e6c40dcf960e5cd045bb4b99d566a60ef1c2467a0653a75a51", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/04dec58fe538938da59b1e072cfbd82576affb0161ce3a3c10e7f51e6d61baedba72b375925c4859b7bfa8b4ab3fda7e80aca2ffdd892e75512bb3dc2d210e43", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ed1a43534518a1e149666a968d70dbcff73e807ccbaeac8e3f6412a577ada331920dbe516e7802255a0ed601d2684d6c38d525a911a3642c574c2b1ecaeccd02"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-03 12:37:57.731240) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 12:37:57.731553) =========
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=eca2ab57546de7b41f256b19000001ef-1509734854.580566-457763756
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004512
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 12:37:57.813940) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-03 12:37:57.814242) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-03 12:37:57.814539) =========
========= Total master_lag: 0.19 =========