builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s-pgo
slave: talos-linux64-ix-059
starttime: 1508974133.26
results: success (0)
revision: a3dc7d2c90781f5e7645a462f73ad736b6dac242
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.256352) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.256784) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.257353) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.313910) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.314192) =========
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=3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-25 16:28:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 98.4K=0.1s
2017-10-25 16:28:53 (98.4 KB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.217918
========= master_lag: 0.05 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.580104) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.580403) =========
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=3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.017859
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:53.629369) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-25 16:28:53.629687) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3dc7d2c90781f5e7645a462f73ad736b6dac242 --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 a3dc7d2c90781f5e7645a462f73ad736b6dac242 --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=3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-25 16:28:53,698 truncating revision to first 12 chars
2017-10-25 16:28:53,698 Setting DEBUG logging.
2017-10-25 16:28:53,698 attempt 1/10
2017-10-25 16:28:53,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3dc7d2c9078?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-25 16:28:54,485 unpacking tar archive at: mozilla-central-a3dc7d2c9078/testing/mozharness/
program finished with exit code 0
elapsedTime=1.518567
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-25 16:28:55.172509) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:55.172842) =========
script_repo_revision: a3dc7d2c90781f5e7645a462f73ad736b6dac242
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:55.173237) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:55.173505) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-25 16:28:55.188343) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 50 secs) (at 2017-10-25 16:28:55.188640) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-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-stylo-disabled-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=3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
16:28:55 INFO - MultiFileLogger online at 20171025 16:28:55 in /builds/slave/test
16:28:55 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
16:28:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
16:28:55 INFO - {'append_to_log': False,
16:28:55 INFO - 'base_work_dir': '/builds/slave/test',
16:28:55 INFO - 'blob_upload_branch': 'Firefox',
16:28:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
16:28:55 INFO - 'branch': 'Firefox',
16:28:55 INFO - 'buildbot_json_path': 'buildprops.json',
16:28:55 INFO - 'code_coverage': False,
16:28:55 INFO - 'config_files': ('talos/linux_config.py',),
16:28:55 INFO - 'default_actions': ('clobber',
16:28:55 INFO - 'read-buildbot-config',
16:28:55 INFO - 'download-and-extract',
16:28:55 INFO - 'populate-webroot',
16:28:55 INFO - 'create-virtualenv',
16:28:55 INFO - 'install',
16:28:55 INFO - 'setup-mitmproxy',
16:28:55 INFO - 'run-tests'),
16:28:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
16:28:55 INFO - 'disable_ccov_upload': False,
16:28:55 INFO - 'disable_stylo': False,
16:28:55 INFO - 'download_minidump_stackwalk': True,
16:28:55 INFO - 'download_symbols': 'ondemand',
16:28:55 INFO - 'enable_stylo': False,
16:28:55 INFO - 'enable_webrender': False,
16:28:55 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
16:28:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
16:28:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
16:28:55 INFO - 'gecko_profile': False,
16:28:55 INFO - 'gecko_profile_interval': 0,
16:28:55 INFO - 'installer_path': 'installer.exe',
16:28:55 INFO - 'log_level': 'info',
16:28:55 INFO - 'log_name': 'talos',
16:28:55 INFO - 'log_to_console': True,
16:28:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
16:28:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
16:28:55 INFO - 'opt_config_files': (),
16:28:55 INFO - 'pip_index': False,
16:28:55 INFO - 'suite': 'g4-stylo-disabled-e10s',
16:28:55 INFO - 'system_bits': '32',
16:28:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
16:28:55 INFO - 'title': 'talos-linux64-ix-059',
16:28:55 INFO - 'tooltool_cache': '/builds/tooltool_cache',
16:28:55 INFO - 'use_talos_json': True,
16:28:55 INFO - 'verify': 'False',
16:28:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
16:28:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:28:55 INFO - 'work_dir': 'build'}
16:28:55 INFO - [mozharness: 2017-10-25 23:28:55.389979Z] Running clobber step.
16:28:55 INFO - Running pre-action listener: _resource_record_pre_action
16:28:55 INFO - Running main action method: clobber
16:28:55 INFO - rmtree: /builds/slave/test/build
16:28:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
16:28:56 INFO - Running post-action listener: _resource_record_post_action
16:28:56 INFO - [mozharness: 2017-10-25 23:28:56.443182Z] Finished clobber step (success)
16:28:56 INFO - [mozharness: 2017-10-25 23:28:56.443279Z] Running read-buildbot-config step.
16:28:56 INFO - Running pre-action listener: _resource_record_pre_action
16:28:56 INFO - Running main action method: read_buildbot_config
16:28:56 INFO - Using buildbot properties:
16:28:56 INFO - {
16:28:56 INFO - "project": "",
16:28:56 INFO - "product": "firefox",
16:28:56 INFO - "who": "archaeopteryx@coole-files.de",
16:28:56 INFO - "installer_path": "public/build/target.tar.bz2",
16:28:56 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
16:28:56 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
16:28:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-stylo-disabled-e10s",
16:28:56 INFO - "stage_platform": "linux64-pgo",
16:28:56 INFO - "basedir": "/builds/slave/test",
16:28:56 INFO - "buildnumber": 135,
16:28:56 INFO - "platform": "ubuntu64_hw",
16:28:56 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
16:28:56 INFO - "slavebuilddir": "test",
16:28:56 INFO - "taskId": "I7YmLGDyQwmvW0YaHaKW0Q",
16:28:56 INFO - "branch": "mozilla-central",
16:28:56 INFO - "script_repo_revision": "production",
16:28:56 INFO - "revision": "a3dc7d2c90781f5e7645a462f73ad736b6dac242",
16:28:56 INFO - "slavename": "talos-linux64-ix-059",
16:28:56 INFO - "repo_path": "mozilla-central"
16:28:56 INFO - }
16:28:56 INFO - Finding installer, test and symbols from parent task.
16:28:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I7YmLGDyQwmvW0YaHaKW0Q'}, attempt #1
16:28:56 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I7YmLGDyQwmvW0YaHaKW0Q'}, attempt #1
16:28:57 INFO - Task dependencies: J9_dg7rvT3iO4Ce00dRMbA
16:28:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA'}, attempt #1
16:28:57 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2
16:28:57 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.test_packages.json
16:28:57 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.crashreporter-symbols.zip
16:28:57 INFO - Running post-action listener: _resource_record_post_action
16:28:57 INFO - [mozharness: 2017-10-25 23:28:57.801185Z] Finished read-buildbot-config step (success)
16:28:57 INFO - [mozharness: 2017-10-25 23:28:57.801447Z] Running download-and-extract step.
16:28:57 INFO - Running pre-action listener: _resource_record_pre_action
16:28:57 INFO - Running main action method: download_and_extract
16:28:57 INFO - mkdir: /builds/slave/test/build/tests
16:28:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:28:57 INFO - trying https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.test_packages.json
16:28:57 INFO - Downloading https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
16:28:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
16:28:58 INFO - Downloaded 989 bytes.
16:28:58 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
16:28:58 INFO - Using the following test package requirements:
16:28:58 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
16:28:58 INFO - u'common': [u'target.common.tests.zip'],
16:28:58 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
16:28:58 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
16:28:58 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
16:28:58 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
16:28:58 INFO - u'mozbase': [u'target.common.tests.zip'],
16:28:58 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
16:28:58 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
16:28:58 INFO - u'web-platform': [u'target.common.tests.zip',
16:28:58 INFO - u'target.web-platform.tests.tar.gz'],
16:28:58 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
16:28:58 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
16:28:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.common.tests.zip
16:28:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.common.tests.zip'}, attempt #1
16:28:58 INFO - Fetch https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.common.tests.zip into memory
16:29:00 INFO - Content-Length response header: 56600861
16:29:00 INFO - Bytes received: 56600861
16:29:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.talos.tests.zip
16:29:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
16:29:11 INFO - Fetch https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.talos.tests.zip into memory
16:29:12 INFO - Content-Length response header: 18737999
16:29:12 INFO - Bytes received: 18737999
16:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:12 INFO - trying https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2
16:29:12 INFO - Downloading https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
16:29:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
16:29:14 INFO - Downloaded 63359743 bytes.
16:29:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2
16:29:14 INFO - mkdir: /builds/slave/test/properties
16:29:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
16:29:14 INFO - Writing to file /builds/slave/test/properties/build_url
16:29:14 INFO - Contents:
16:29:14 INFO - build_url:https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2
16:29:14 INFO - Running post-action listener: _resource_record_post_action
16:29:14 INFO - Running post-action listener: find_tests_for_verification
16:29:14 INFO - Running post-action listener: set_extra_try_arguments
16:29:14 INFO - [mozharness: 2017-10-25 23:29:14.354521Z] Finished download-and-extract step (success)
16:29:14 INFO - [mozharness: 2017-10-25 23:29:14.354632Z] Running populate-webroot step.
16:29:14 INFO - Running pre-action listener: _resource_record_pre_action
16:29:14 INFO - Running main action method: populate_webroot
16:29:14 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
16:29:14 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['tresize',
16:29:14 INFO - 'tcanvasmark']},
16:29:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
16:29:14 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['dromaeo_css',
16:29:14 INFO - 'kraken']},
16:29:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:29:14 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:29:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'tests': ['damp', 'tps']},
16:29:14 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['damp', 'tps']},
16:29:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
16:29:14 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['dromaeo_dom']},
16:29:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
16:29:14 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['basic_compositor_video',
16:29:14 INFO - 'glvideo']},
16:29:14 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
16:29:14 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['ts_paint_webext',
16:29:14 INFO - 'tp5o_webext']},
16:29:14 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
16:29:14 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:29:14 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:29:14 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:29:14 INFO - 'talos_options': ['--mitmproxy',
16:29:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:29:14 INFO - '--firstNonBlankPaint'],
16:29:14 INFO - 'tests': ['tp6_google_heavy',
16:29:14 INFO - 'tp6_youtube_heavy',
16:29:14 INFO - 'tp6_amazon_heavy',
16:29:14 INFO - 'tp6_facebook_heavy']},
16:29:14 INFO - 'other-e10s': {'tests': ['a11yr',
16:29:14 INFO - 'ts_paint',
16:29:14 INFO - 'tpaint',
16:29:14 INFO - 'sessionrestore',
16:29:14 INFO - 'sessionrestore_many_windows',
16:29:14 INFO - 'sessionrestore_no_auto_restore',
16:29:14 INFO - 'tabpaint',
16:29:14 INFO - 'cpstartup']},
16:29:14 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['a11yr',
16:29:14 INFO - 'ts_paint',
16:29:14 INFO - 'tpaint',
16:29:14 INFO - 'sessionrestore',
16:29:14 INFO - 'sessionrestore_many_windows',
16:29:14 INFO - 'sessionrestore_no_auto_restore',
16:29:14 INFO - 'tabpaint',
16:29:14 INFO - 'cpstartup']},
16:29:14 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
16:29:14 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
16:29:14 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['perf_reftest_singletons']},
16:29:14 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['perf_reftest']},
16:29:14 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
16:29:14 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['speedometer']},
16:29:14 INFO - 'svgr-e10s': {'tests': ['tsvgx',
16:29:14 INFO - 'tsvgr_opacity',
16:29:14 INFO - 'tart',
16:29:14 INFO - 'tscrollx',
16:29:14 INFO - 'tsvg_static']},
16:29:14 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['tsvgx',
16:29:14 INFO - 'tsvgr_opacity',
16:29:14 INFO - 'tart',
16:29:14 INFO - 'tscrollx',
16:29:14 INFO - 'tsvg_static']},
16:29:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
16:29:14 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'talos_options': ['--disable-stylo'],
16:29:14 INFO - 'tests': ['tp5o']},
16:29:14 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:29:14 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:29:14 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:29:14 INFO - 'talos_options': ['--mitmproxy',
16:29:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:29:14 INFO - '--firstNonBlankPaint'],
16:29:14 INFO - 'tests': ['tp6_google',
16:29:14 INFO - 'tp6_youtube',
16:29:14 INFO - 'tp6_amazon',
16:29:14 INFO - 'tp6_facebook']},
16:29:14 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:29:14 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:29:14 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:29:14 INFO - 'talos_options': ['--disable-stylo',
16:29:14 INFO - '--mitmproxy',
16:29:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:29:14 INFO - '--firstNonBlankPaint'],
16:29:14 INFO - 'tests': ['tp6_google',
16:29:14 INFO - 'tp6_youtube',
16:29:14 INFO - 'tp6_amazon',
16:29:14 INFO - 'tp6_facebook']},
16:29:14 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:29:14 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:29:14 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:29:14 INFO - 'talos_options': ['--stylo-threads=1',
16:29:14 INFO - '--mitmproxy',
16:29:14 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:29:14 INFO - '--firstNonBlankPaint'],
16:29:14 INFO - 'tests': ['tp6_google',
16:29:14 INFO - 'tp6_youtube',
16:29:14 INFO - 'tp6_amazon',
16:29:14 INFO - 'tp6_facebook']},
16:29:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'talos_options': ['--xperf_path',
16:29:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:29:14 INFO - 'tests': ['tp5n']},
16:29:14 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:29:14 INFO - 'talos_options': ['--disable-stylo',
16:29:14 INFO - '--xperf_path',
16:29:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:29:14 INFO - 'tests': ['tp5n']}}}
16:29:14 INFO - Running post-action listener: _resource_record_post_action
16:29:14 INFO - [mozharness: 2017-10-25 23:29:14.373122Z] Finished populate-webroot step (success)
16:29:14 INFO - [mozharness: 2017-10-25 23:29:14.373218Z] Running create-virtualenv step.
16:29:14 INFO - Running pre-action listener: _resource_record_pre_action
16:29:14 INFO - Running main action method: create_virtualenv
16:29:14 INFO - Creating virtualenv /builds/slave/test/build/venv
16:29:14 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
16:29:14 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
16:29:14 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
16:29:14 INFO - Using real prefix '/usr'
16:29:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python
16:29:14 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
16:29:16 INFO - Installing setuptools, pip, wheel...done.
16:29:16 INFO - Return code: 0
16:29:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
16:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:16 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
16:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
16:29:16 INFO - Using env: {'DISPLAY': ':0',
16:29:16 INFO - 'HOME': '/home/cltbld',
16:29:16 INFO - 'LANG': 'en_US.UTF-8',
16:29:16 INFO - 'LANGUAGE': 'en_US:en',
16:29:16 INFO - 'LOGNAME': 'cltbld',
16:29:16 INFO - 'MAIL': '/var/mail/cltbld',
16:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:16 INFO - 'MOZ_NO_REMOTE': '1',
16:29:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:16 INFO - 'NO_EM_RESTART': '1',
16:29:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:16 INFO - 'PWD': '/builds/slave/test',
16:29:16 INFO - 'SHELL': '/bin/bash',
16:29:16 INFO - 'SHLVL': '1',
16:29:16 INFO - 'TERM': 'linux',
16:29:16 INFO - 'TMOUT': '86400',
16:29:16 INFO - 'USER': 'cltbld',
16:29:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:16 INFO - '_': '/tools/buildbot/bin/python'}
16:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
16:29:16 INFO - Return code: 0
16:29:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
16:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:16 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
16:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
16:29:16 INFO - Using env: {'DISPLAY': ':0',
16:29:16 INFO - 'HOME': '/home/cltbld',
16:29:16 INFO - 'LANG': 'en_US.UTF-8',
16:29:16 INFO - 'LANGUAGE': 'en_US:en',
16:29:16 INFO - 'LOGNAME': 'cltbld',
16:29:16 INFO - 'MAIL': '/var/mail/cltbld',
16:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:16 INFO - 'MOZ_NO_REMOTE': '1',
16:29:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:16 INFO - 'NO_EM_RESTART': '1',
16:29:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:16 INFO - 'PWD': '/builds/slave/test',
16:29:16 INFO - 'SHELL': '/bin/bash',
16:29:16 INFO - 'SHLVL': '1',
16:29:16 INFO - 'TERM': 'linux',
16:29:16 INFO - 'TMOUT': '86400',
16:29:16 INFO - 'USER': 'cltbld',
16:29:16 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:16 INFO - '_': '/tools/buildbot/bin/python'}
16:29:17 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:17 INFO - Collecting psutil>=3.1.1
16:29:18 INFO - Installing collected packages: psutil
16:29:18 INFO - Successfully installed psutil-3.1.1
16:29:18 INFO - Return code: 0
16:29:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
16:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
16:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
16:29:18 INFO - Using env: {'DISPLAY': ':0',
16:29:18 INFO - 'HOME': '/home/cltbld',
16:29:18 INFO - 'LANG': 'en_US.UTF-8',
16:29:18 INFO - 'LANGUAGE': 'en_US:en',
16:29:18 INFO - 'LOGNAME': 'cltbld',
16:29:18 INFO - 'MAIL': '/var/mail/cltbld',
16:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:18 INFO - 'MOZ_NO_REMOTE': '1',
16:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:18 INFO - 'NO_EM_RESTART': '1',
16:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:18 INFO - 'PWD': '/builds/slave/test',
16:29:18 INFO - 'SHELL': '/bin/bash',
16:29:18 INFO - 'SHLVL': '1',
16:29:18 INFO - 'TERM': 'linux',
16:29:18 INFO - 'TMOUT': '86400',
16:29:18 INFO - 'USER': 'cltbld',
16:29:18 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:18 INFO - '_': '/tools/buildbot/bin/python'}
16:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:19 INFO - Collecting mozsystemmonitor==0.3
16:29:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
16:29:20 INFO - Installing collected packages: mozsystemmonitor
16:29:20 INFO - Successfully installed mozsystemmonitor-0.3
16:29:20 INFO - Return code: 0
16:29:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
16:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:20 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
16:29:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
16:29:20 INFO - Using env: {'DISPLAY': ':0',
16:29:20 INFO - 'HOME': '/home/cltbld',
16:29:20 INFO - 'LANG': 'en_US.UTF-8',
16:29:20 INFO - 'LANGUAGE': 'en_US:en',
16:29:20 INFO - 'LOGNAME': 'cltbld',
16:29:20 INFO - 'MAIL': '/var/mail/cltbld',
16:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:20 INFO - 'MOZ_NO_REMOTE': '1',
16:29:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:20 INFO - 'NO_EM_RESTART': '1',
16:29:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:20 INFO - 'PWD': '/builds/slave/test',
16:29:20 INFO - 'SHELL': '/bin/bash',
16:29:20 INFO - 'SHLVL': '1',
16:29:20 INFO - 'TERM': 'linux',
16:29:20 INFO - 'TMOUT': '86400',
16:29:20 INFO - 'USER': 'cltbld',
16:29:20 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:20 INFO - '_': '/tools/buildbot/bin/python'}
16:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:20 INFO - Collecting jsonschema==2.5.1
16:29:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
16:29:22 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
16:29:23 INFO - Installing collected packages: functools32, jsonschema
16:29:23 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
16:29:23 INFO - Return code: 0
16:29:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:23 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
16:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
16:29:23 INFO - Using env: {'DISPLAY': ':0',
16:29:23 INFO - 'HOME': '/home/cltbld',
16:29:23 INFO - 'LANG': 'en_US.UTF-8',
16:29:23 INFO - 'LANGUAGE': 'en_US:en',
16:29:23 INFO - 'LOGNAME': 'cltbld',
16:29:23 INFO - 'MAIL': '/var/mail/cltbld',
16:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:23 INFO - 'MOZ_NO_REMOTE': '1',
16:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:23 INFO - 'NO_EM_RESTART': '1',
16:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:23 INFO - 'PWD': '/builds/slave/test',
16:29:23 INFO - 'SHELL': '/bin/bash',
16:29:23 INFO - 'SHLVL': '1',
16:29:23 INFO - 'TERM': 'linux',
16:29:23 INFO - 'TMOUT': '86400',
16:29:23 INFO - 'USER': 'cltbld',
16:29:23 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:23 INFO - '_': '/tools/buildbot/bin/python'}
16:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
16:29:23 INFO - Return code: 0
16:29:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:23 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
16:29:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
16:29:23 INFO - Using env: {'DISPLAY': ':0',
16:29:23 INFO - 'HOME': '/home/cltbld',
16:29:23 INFO - 'LANG': 'en_US.UTF-8',
16:29:23 INFO - 'LANGUAGE': 'en_US:en',
16:29:23 INFO - 'LOGNAME': 'cltbld',
16:29:23 INFO - 'MAIL': '/var/mail/cltbld',
16:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:23 INFO - 'MOZ_NO_REMOTE': '1',
16:29:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:23 INFO - 'NO_EM_RESTART': '1',
16:29:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:23 INFO - 'PWD': '/builds/slave/test',
16:29:23 INFO - 'SHELL': '/bin/bash',
16:29:23 INFO - 'SHLVL': '1',
16:29:23 INFO - 'TERM': 'linux',
16:29:23 INFO - 'TMOUT': '86400',
16:29:23 INFO - 'USER': 'cltbld',
16:29:23 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:23 INFO - '_': '/tools/buildbot/bin/python'}
16:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:24 INFO - Collecting blobuploader==1.2.4
16:29:25 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
16:29:26 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
16:29:27 INFO - Installing collected packages: requests, docopt, blobuploader
16:29:27 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
16:29:27 INFO - Return code: 0
16:29:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:29: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')]}
16:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29: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')]}
16:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:27 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
16:29:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
16:29:27 INFO - Using env: {'DISPLAY': ':0',
16:29:27 INFO - 'HOME': '/home/cltbld',
16:29:27 INFO - 'LANG': 'en_US.UTF-8',
16:29:27 INFO - 'LANGUAGE': 'en_US:en',
16:29:27 INFO - 'LOGNAME': 'cltbld',
16:29:27 INFO - 'MAIL': '/var/mail/cltbld',
16:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:27 INFO - 'MOZ_NO_REMOTE': '1',
16:29:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:27 INFO - 'NO_EM_RESTART': '1',
16:29:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:27 INFO - 'PWD': '/builds/slave/test',
16:29:27 INFO - 'SHELL': '/bin/bash',
16:29:27 INFO - 'SHLVL': '1',
16:29:27 INFO - 'TERM': 'linux',
16:29:27 INFO - 'TMOUT': '86400',
16:29:27 INFO - 'USER': 'cltbld',
16:29:27 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:27 INFO - '_': '/tools/buildbot/bin/python'}
16:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:27 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
16:29:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
16:29:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
16:29:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
16:29:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
16:29:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
16:29:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
16:29:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
16:29:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
16:29:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
16:29:29 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
16:29:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
16:29:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
16:29:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
16:29:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
16:29:30 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
16:29:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
16:29:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
16:29:31 INFO - Running setup.py install for manifestparser: started
16:29:31 INFO - Running setup.py install for manifestparser: finished with status 'done'
16:29:31 INFO - Running setup.py install for mozcrash: started
16:29:31 INFO - Running setup.py install for mozcrash: finished with status 'done'
16:29:31 INFO - Running setup.py install for mozdebug: started
16:29:31 INFO - Running setup.py install for mozdebug: finished with status 'done'
16:29:31 INFO - Running setup.py install for mozdevice: started
16:29:32 INFO - Running setup.py install for mozdevice: finished with status 'done'
16:29:32 INFO - Running setup.py install for mozfile: started
16:29:32 INFO - Running setup.py install for mozfile: finished with status 'done'
16:29:32 INFO - Running setup.py install for mozhttpd: started
16:29:32 INFO - Running setup.py install for mozhttpd: finished with status 'done'
16:29:32 INFO - Running setup.py install for mozinfo: started
16:29:32 INFO - Running setup.py install for mozinfo: finished with status 'done'
16:29:32 INFO - Running setup.py install for mozInstall: started
16:29:32 INFO - Running setup.py install for mozInstall: finished with status 'done'
16:29:32 INFO - Running setup.py install for mozleak: started
16:29:33 INFO - Running setup.py install for mozleak: finished with status 'done'
16:29:33 INFO - Running setup.py install for mozlog: started
16:29:33 INFO - Running setup.py install for mozlog: finished with status 'done'
16:29:33 INFO - Running setup.py install for moznetwork: started
16:29:33 INFO - Running setup.py install for moznetwork: finished with status 'done'
16:29:33 INFO - Running setup.py install for mozprocess: started
16:29:33 INFO - Running setup.py install for mozprocess: finished with status 'done'
16:29:33 INFO - Running setup.py install for mozprofile: started
16:29:33 INFO - Running setup.py install for mozprofile: finished with status 'done'
16:29:33 INFO - Running setup.py install for mozrunner: started
16:29:34 INFO - Running setup.py install for mozrunner: finished with status 'done'
16:29:34 INFO - Running setup.py install for mozscreenshot: started
16:29:34 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
16:29:34 INFO - Running setup.py install for moztest: started
16:29:34 INFO - Running setup.py install for moztest: finished with status 'done'
16:29:34 INFO - Running setup.py install for mozversion: started
16:29:34 INFO - Running setup.py install for mozversion: finished with status 'done'
16:29:35 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
16:29:35 INFO - Return code: 0
16:29:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:29: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')]}
16:29:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29: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')]}
16:29:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:35 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
16:29:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
16:29:35 INFO - Using env: {'DISPLAY': ':0',
16:29:35 INFO - 'HOME': '/home/cltbld',
16:29:35 INFO - 'LANG': 'en_US.UTF-8',
16:29:35 INFO - 'LANGUAGE': 'en_US:en',
16:29:35 INFO - 'LOGNAME': 'cltbld',
16:29:35 INFO - 'MAIL': '/var/mail/cltbld',
16:29:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:35 INFO - 'MOZ_NO_REMOTE': '1',
16:29:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:35 INFO - 'NO_EM_RESTART': '1',
16:29:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:35 INFO - 'PWD': '/builds/slave/test',
16:29:35 INFO - 'SHELL': '/bin/bash',
16:29:35 INFO - 'SHLVL': '1',
16:29:35 INFO - 'TERM': 'linux',
16:29:35 INFO - 'TMOUT': '86400',
16:29:35 INFO - 'USER': 'cltbld',
16:29:35 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:35 INFO - '_': '/tools/buildbot/bin/python'}
16:29:35 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:35 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
16:29:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
16:29:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:29:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
16:29:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:29:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
16:29:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
16:29:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
16:29:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
16:29:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
16:29:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
16:29:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
16:29:37 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
16:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
16:29:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
16:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
16:29:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
16:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
16:29:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
16:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
16:29:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
16:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
16:29:38 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
16:29:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
16:29:38 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
16:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
16:29:39 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
16:29:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
16:29:40 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
16:29:40 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
16:29:41 INFO - Installing collected packages: six, blessings
16:29:41 INFO - Successfully installed blessings-1.6 six-1.10.0
16:29:41 INFO - Return code: 0
16:29:41 INFO - Done creating virtualenv /builds/slave/test/build/venv.
16:29:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:29:41 INFO - Reading from file tmpfile_stdout
16:29:41 INFO - Reading from file tmpfile_stderr
16:29:41 INFO - Current package versions:
16:29:41 INFO - blessings == 1.6
16:29:41 INFO - blobuploader == 1.2.4
16:29:41 INFO - docopt == 0.6.1
16:29:41 INFO - functools32 == 3.2.3.post2
16:29:41 INFO - jsonschema == 2.5.1
16:29:41 INFO - manifestparser == 1.2
16:29:41 INFO - mozInstall == 1.14
16:29:41 INFO - mozcrash == 1.0
16:29:41 INFO - mozdebug == 0.1
16:29:41 INFO - mozdevice == 0.51
16:29:41 INFO - mozfile == 1.2
16:29:41 INFO - mozhttpd == 0.7
16:29:41 INFO - mozinfo == 0.10
16:29:41 INFO - mozleak == 0.1
16:29:41 INFO - mozlog == 3.5
16:29:41 INFO - moznetwork == 0.27
16:29:41 INFO - mozprocess == 0.25
16:29:41 INFO - mozprofile == 0.29
16:29:41 INFO - mozrunner == 6.13
16:29:41 INFO - mozscreenshot == 0.1
16:29:41 INFO - mozsystemmonitor == 0.3
16:29:41 INFO - moztest == 0.8
16:29:41 INFO - mozversion == 1.4
16:29:41 INFO - psutil == 3.1.1
16:29:41 INFO - requests == 1.2.3
16:29:41 INFO - six == 1.10.0
16:29:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:41 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 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
16:29:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
16:29:41 INFO - Using env: {'DISPLAY': ':0',
16:29:41 INFO - 'HOME': '/home/cltbld',
16:29:41 INFO - 'LANG': 'en_US.UTF-8',
16:29:41 INFO - 'LANGUAGE': 'en_US:en',
16:29:41 INFO - 'LOGNAME': 'cltbld',
16:29:41 INFO - 'MAIL': '/var/mail/cltbld',
16:29:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:41 INFO - 'MOZ_NO_REMOTE': '1',
16:29:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:41 INFO - 'NO_EM_RESTART': '1',
16:29:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:41 INFO - 'PWD': '/builds/slave/test',
16:29:41 INFO - 'SHELL': '/bin/bash',
16:29:41 INFO - 'SHLVL': '1',
16:29:41 INFO - 'TERM': 'linux',
16:29:41 INFO - 'TMOUT': '86400',
16:29:41 INFO - 'USER': 'cltbld',
16:29:41 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:41 INFO - '_': '/tools/buildbot/bin/python'}
16:29:42 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
16:29:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
16:29:42 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
16:29:43 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
16:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
16:29:44 INFO - Installing collected packages: simplejson, requests
16:29:44 INFO - Found existing installation: requests 1.2.3
16:29:44 INFO - Uninstalling requests-1.2.3:
16:29:44 INFO - Successfully uninstalled requests-1.2.3
16:29:44 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
16:29:44 INFO - Return code: 0
16:29:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
16:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:29:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:29:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15ec1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17b6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1959cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, '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': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
16:29:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
16:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
16:29:44 INFO - Using env: {'DISPLAY': ':0',
16:29:44 INFO - 'HOME': '/home/cltbld',
16:29:44 INFO - 'LANG': 'en_US.UTF-8',
16:29:44 INFO - 'LANGUAGE': 'en_US:en',
16:29:44 INFO - 'LOGNAME': 'cltbld',
16:29:44 INFO - 'MAIL': '/var/mail/cltbld',
16:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:29:44 INFO - 'MOZ_NO_REMOTE': '1',
16:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:29:44 INFO - 'NO_EM_RESTART': '1',
16:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:29:44 INFO - 'PWD': '/builds/slave/test',
16:29:44 INFO - 'SHELL': '/bin/bash',
16:29:44 INFO - 'SHLVL': '1',
16:29:44 INFO - 'TERM': 'linux',
16:29:44 INFO - 'TMOUT': '86400',
16:29:44 INFO - 'USER': 'cltbld',
16:29:44 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:29:44 INFO - '_': '/tools/buildbot/bin/python'}
16:29:45 INFO - Ignoring indexes: https://pypi.python.org/simple
16:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
16:29:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
16:29:45 INFO - Return code: 0
16:29:45 INFO - Running post-action listener: _resource_record_post_action
16:29:45 INFO - Running post-action listener: _start_resource_monitoring
16:29:45 INFO - Starting resource monitoring.
16:29:45 INFO - [mozharness: 2017-10-25 23:29:45.462239Z] Finished create-virtualenv step (success)
16:29:45 INFO - [mozharness: 2017-10-25 23:29:45.462739Z] Running install step.
16:29:45 INFO - Running pre-action listener: _resource_record_pre_action
16:29:45 INFO - Running main action method: install
16:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:29:45 INFO - Reading from file tmpfile_stdout
16:29:45 INFO - Reading from file tmpfile_stderr
16:29:45 INFO - Detecting whether we're running mozinstall >=1.0...
16:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
16:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
16:29:46 INFO - Reading from file tmpfile_stdout
16:29:46 INFO - Output received:
16:29:46 INFO - Usage: mozinstall [options] installer
16:29:46 INFO - Options:
16:29:46 INFO - -h, --help show this help message and exit
16:29:46 INFO - -d DEST, --destination=DEST
16:29:46 INFO - Directory to install application into. [default:
16:29:46 INFO - "/builds/slave/test"]
16:29:46 INFO - --app=APP Application being installed. [default: firefox]
16:29:46 INFO - mkdir: /builds/slave/test/build/application
16:29:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
16:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
16:30:04 INFO - Reading from file tmpfile_stdout
16:30:04 INFO - Output received:
16:30:04 INFO - /builds/slave/test/build/application/firefox/firefox
16:30:04 INFO - Running post-action listener: _resource_record_post_action
16:30:04 INFO - [mozharness: 2017-10-25 23:30:04.215628Z] Finished install step (success)
16:30:04 INFO - [mozharness: 2017-10-25 23:30:04.215746Z] Running setup-mitmproxy step.
16:30:04 INFO - Running pre-action listener: _resource_record_pre_action
16:30:04 INFO - Running main action method: setup_mitmproxy
16:30:04 INFO - Skipping: mitmproxy is not required
16:30:04 INFO - Running post-action listener: _resource_record_post_action
16:30:04 INFO - [mozharness: 2017-10-25 23:30:04.216272Z] Finished setup-mitmproxy step (success)
16:30:04 INFO - [mozharness: 2017-10-25 23:30:04.216374Z] Running run-tests step.
16:30:04 INFO - Running pre-action listener: _resource_record_pre_action
16:30:04 INFO - Running pre-action listener: _set_gcov_prefix
16:30:04 INFO - Running main action method: run_tests
16:30:04 WARNING - Try message not found.
16:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
16:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
16:30:04 INFO - Python 2.7.3
16:30:04 INFO - Return code: 0
16:30:04 INFO - grabbing minidump binary from tooltool
16:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:30:04 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1956e60>, '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 0x1957d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19581f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
16:30:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
16:30:04 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
16:30:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
16:30:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
16:30:04 INFO - Return code: 0
16:30:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
16:30:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
16:30:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
16:30:04 INFO - ENV: RUST_BACKTRACE is now full
16:30:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
16:30:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
16:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build
16:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log
16:30:04 INFO - Using env: {'DISPLAY': ':0',
16:30:04 INFO - 'HOME': '/home/cltbld',
16:30:04 INFO - 'LANG': 'en_US.UTF-8',
16:30:04 INFO - 'LANGUAGE': 'en_US:en',
16:30:04 INFO - 'LOGNAME': 'cltbld',
16:30:04 INFO - 'MAIL': '/var/mail/cltbld',
16:30:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:30:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:30:04 INFO - 'MOZ_NO_REMOTE': '1',
16:30:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:30:04 INFO - 'NO_EM_RESTART': '1',
16:30:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:30:04 INFO - 'PWD': '/builds/slave/test',
16:30:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:30:04 INFO - 'RUST_BACKTRACE': 'full',
16:30:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:30:04 INFO - 'SHELL': '/bin/bash',
16:30:04 INFO - 'SHLVL': '1',
16:30:04 INFO - 'TERM': 'linux',
16:30:04 INFO - 'TMOUT': '86400',
16:30:04 INFO - 'USER': 'cltbld',
16:30:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:30:04 INFO - '_': '/tools/buildbot/bin/python'}
16:30:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] with output_timeout 3600
16:30:04 INFO - mozversion application_buildid: 20171025220004
16:30:04 INFO - mozversion application_changeset: a3dc7d2c90781f5e7645a462f73ad736b6dac242
16:30:04 INFO - mozversion application_display_name: Nightly
16:30:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
16:30:04 INFO - mozversion application_name: Firefox
16:30:04 INFO - mozversion application_remotingname: firefox
16:30:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
16:30:04 INFO - mozversion application_vendor: Mozilla
16:30:04 INFO - mozversion application_version: 58.0a1
16:30:04 INFO - mozversion platform_buildid: 20171025220004
16:30:04 INFO - mozversion platform_changeset: a3dc7d2c90781f5e7645a462f73ad736b6dac242
16:30:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
16:30:04 INFO - mozversion platform_version: 58.0a1
16:30:04 INFO - using testdate: 1508974204
16:30:04 INFO - actual date: 1508974204
16:30:04 INFO - starting webserver on 'localhost:46180'
16:30:04 INFO - SUITE-START | Running 2 tests
16:30:04 INFO - TEST-START | basic_compositor_video
16:30:04 INFO - Initialising browser for basic_compositor_video test...
16:30:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46180/getInfo.html -profile /tmp/tmpIrCxIX/profile
16:30:04 INFO - TEST-INFO | started process 5426 (/builds/slave/test/build/application/firefox/firefox http://localhost:46180/getInfo.html)
16:30:09 INFO - TEST-INFO | 5426: exit 0
16:30:09 INFO - Browser initialized.
16:30:09 INFO - Running cycle 1/1 for basic_compositor_video test...
16:30:09 INFO - Using env: {'DISPLAY': ':0',
16:30:09 INFO - 'HOME': '/home/cltbld',
16:30:09 INFO - 'JSGC_DISABLE_POISONING': '1',
16:30:09 INFO - 'LANG': 'en_US.UTF-8',
16:30:09 INFO - 'LANGUAGE': 'en_US:en',
16:30:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:30:09 INFO - 'LOGNAME': 'cltbld',
16:30:09 INFO - 'MAIL': '/var/mail/cltbld',
16:30:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:30:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:30:09 INFO - 'MOZ_CRASHREPORTER': '1',
16:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:30:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:30:09 INFO - 'MOZ_NO_REMOTE': '1',
16:30:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:30:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:30:09 INFO - 'NO_EM_RESTART': '1',
16:30:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:30:09 INFO - 'PWD': '/builds/slave/test',
16:30:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:30:09 INFO - 'RUST_BACKTRACE': 'full',
16:30:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:30:09 INFO - 'SHELL': '/bin/bash',
16:30:09 INFO - 'SHLVL': '1',
16:30:09 INFO - 'STYLO_FORCE_DISABLED': '1',
16:30:09 INFO - 'TERM': 'linux',
16:30:09 INFO - 'TMOUT': '86400',
16:30:09 INFO - 'USER': 'cltbld',
16:30:09 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:30:09 INFO - '_': '/tools/buildbot/bin/python'}
16:30:09 INFO - TEST-INFO | started process 5696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIrCxIX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
16:30:10 INFO - PID 5696 |
16:30:10 INFO - PID 5696 | (/builds/slave/test/build/application/firefox/firefox:5747): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:30:10 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6791634023503077 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6702587646076792 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7690801886792455 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7660723742277133 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8969350649350667 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9780616302186846 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.4252111872146105 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.384407783417938 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.475164233576641 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.541246537396121 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.136418918918922 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.204336734693873 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.112555555555566 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.698823529411767 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.876115879828303 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.1978353658537 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.812598425196828 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113666666666703 ms/frame
16:30:56 INFO - PID 5696 |
16:30:56 INFO - PID 5696 | Cycle 1(1): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:31:42 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822057174887894 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713408521303257 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7494897959183673 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7201461736887367 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.122087912087913 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.717983271375464 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.534004711425207 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.437139175257727 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.55575925925926 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589594972067041 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.276044520547943 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 9.95330845771145 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.409220532319406 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.93371584699451 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.712415254237277 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.348395061728409 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583108108108114 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.112638888888897 ms/frame
16:31:42 INFO - PID 5696 |
16:31:42 INFO - PID 5696 | Cycle 1(2): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:32:29 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6932308126410833 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682902418682237 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7795788849347567 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7770870337477807 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.208632538569423 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.100245901639345 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.4691445086705195 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.380076013513515 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682926136363634 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587681564245819 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.274520547945205 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.257179487179476 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.453854961832075 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.872255434782584 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.825064102564081 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.425062111801273 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.906329365079353 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.1125 ms/frame
16:32:29 INFO - PID 5696 |
16:32:29 INFO - PID 5696 | Cycle 1(3): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:33:15 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686683530073075 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6714076858813698 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.742746806039489 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7307093425605535 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.16128294036061 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.108049281314166 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.5684066587395966 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.479417391304349 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.578057620817842 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.541551246537396 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.240733788395916 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.104772727272723 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.409904942965767 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.990686813186814 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.823290598290605 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.424223602484444 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451145038167928 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.051546961325977 ms/frame
16:33:15 INFO - PID 5696 |
16:33:15 INFO - PID 5696 | Cycle 1(4): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:34:01 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6865345699831367 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6671541666666663 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7967095808383233 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7778888888888889 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.208793828892004 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.212652631578948 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.4368613974799556 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4493189655172407 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.516544117647059 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.450681198910086 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.453780487804867 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.104823232323238 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.407376425855512 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.758467741935508 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.76672340425532 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.426739130434793 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.280883458646603 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.238567415730317 ms/frame
16:34:01 INFO - PID 5696 |
16:34:01 INFO - PID 5696 | Cycle 1(5): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:34:47 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.68001679731243 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668940783986655 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7879231227651968 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7476026200873362 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.076277173913045 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.091860940695293 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.496794871794874 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4362886597938114 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7260782442748095 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.602941176470588 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.64198581560284 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.153121827411171 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.156524163568784 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.752661290322555 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.662995780590705 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.125515151515145 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.673424124513618 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.116416666666636 ms/frame
16:34:47 INFO - PID 5696 |
16:34:47 INFO - PID 5696 | Cycle 1(6): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:35:10 INFO - PID 5696 |
16:35:10 INFO - PID 5696 | (/builds/slave/test/build/application/firefox/firefox:5828): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:35:10 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6867594154019112 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689491242702257 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7445029069767441 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7380017376194619 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.244108910891088 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8101142857142847 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.4726620370370362 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4015561224489823 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.587811918063313 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.451498637602182 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.13591216216216 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 9.85302955665025 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.281729323308266 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.812432432432448 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.767574468085096 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.506312500000012 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.496111111111114 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17829608938548 ms/frame
16:35:33 INFO - PID 5696 |
16:35:33 INFO - PID 5696 | Cycle 1(7): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:36:19 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680025195968645 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684945788156795 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7741957421643997 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7591468777484613 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.144516574585637 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.017329317269074 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.469202312138729 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.368038720538723 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693605313092977 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.698675213675215 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.27433219178082 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.259846153846144 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239382022471913 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.700374331550808 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.045326086956534 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.352160493827133 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58519305019303 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.75508064516129 ms/frame
16:36:19 INFO - PID 5696 |
16:36:19 INFO - PID 5696 | Cycle 1(8): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:37:05 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6865008431703203 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673208333333331 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7986750599520387 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7907430617726046 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.132679063360882 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1841527196652715 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.6415533980582495 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5407522123893833 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.748984674329497 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.651200564971756 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.275051369863009 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 9.479786729857823 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.324566037735844 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.87529891304348 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.55271966527196 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050813253012034 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.954900398406346 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111861111111123 ms/frame
16:37:05 INFO - PID 5696 |
16:37:05 INFO - PID 5696 | Cycle 1(9): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:37:52 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819198430493274 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6705676126878128 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7850892325996428 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7430095818815325 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9897805851063834 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.184497907949792 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.501878646441074 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3909406779660998 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.74840996168582 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603963585434178 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 9.837393442622947 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.587063492063482 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114870370370355 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.581825396825417 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.609369747899185 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.503468749999957 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.765450980392165 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.241011235955023 ms/frame
16:37:52 INFO - PID 5696 |
16:37:52 INFO - PID 5696 | Cycle 1(10): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:38:38 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6875590551181103 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6653288925895082 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7620845566647092 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.782762923351158 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.14493093922652 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.159365904365906 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.457062211981566 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3901864406779647 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.738183556405353 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683309659090903 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.490227272727266 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 9.807598039215685 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.407148288973376 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.696069518716582 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.988528138528164 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.271595092024521 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.721738281249998 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.994587912087908 ms/frame
16:38:38 INFO - PID 5696 |
16:38:38 INFO - PID 5696 | Cycle 1(11): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:39:24 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6944466403162055 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713199665831242 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7387369640787946 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.722906976744187 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116488340192044 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.041363636363637 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_startup = 3.452652474108172 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3728836424957827 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7601343570057555 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634464788732396 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_startup = 10.070151006711418 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.480p.60fps.webm_scale_2_inclip = 10.000625 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.45314885496184 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.815810810810786 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.773531914893612 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.346604938271614 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.676381322957205 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.30031073446327 ms/frame
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | Cycle 1(12): loaded http://localhost:46180/tests/video/video_playback.html (next: http://localhost:46180/tests/video/video_playback.html)
16:39:24 INFO - PID 5696 | __start_tp_report
16:39:24 INFO - PID 5696 | _x_x_mozilla_page_load
16:39:24 INFO - PID 5696 | _x_x_mozilla_page_load_details
16:39:24 INFO - PID 5696 | |i|pagename|runs|
16:39:24 INFO - PID 5696 | |0;240p.120fps.mp4_scale_1_startup;1.6791634023503077;1.6822057174887894;1.6932308126410833;1.686683530073075;1.6865345699831367;1.68001679731243;1.6867594154019112;1.680025195968645;1.6865008431703203;1.6819198430493274;1.6875590551181103;1.6944466403162055
16:39:24 INFO - PID 5696 | |1;240p.120fps.mp4_scale_1_inclip;1.6702587646076792;1.6713408521303257;1.6682902418682237;1.6714076858813698;1.6671541666666663;1.668940783986655;1.6689491242702257;1.6684945788156795;1.6673208333333331;1.6705676126878128;1.6653288925895082;1.6713199665831242
16:39:24 INFO - PID 5696 | |2;240p.120fps.mp4_scale_1.1_startup;1.7690801886792455;1.7494897959183673;1.7795788849347567;1.742746806039489;1.7967095808383233;1.7879231227651968;1.7445029069767441;1.7741957421643997;1.7986750599520387;1.7850892325996428;1.7620845566647092;1.7387369640787946
16:39:24 INFO - PID 5696 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7660723742277133;1.7201461736887367;1.7770870337477807;1.7307093425605535;1.7778888888888889;1.7476026200873362;1.7380017376194619;1.7591468777484613;1.7907430617726046;1.7430095818815325;1.782762923351158;1.722906976744187
16:39:24 INFO - PID 5696 | |4;240p.120fps.mp4_scale_2_startup;3.8969350649350667;4.122087912087913;4.208632538569423;4.16128294036061;4.208793828892004;4.076277173913045;4.244108910891088;4.144516574585637;4.132679063360882;3.9897805851063834;4.14493093922652;4.116488340192044
16:39:24 INFO - PID 5696 | |5;240p.120fps.mp4_scale_2_inclip;3.9780616302186846;3.717983271375464;4.100245901639345;4.108049281314166;4.212652631578948;4.091860940695293;3.8101142857142847;4.017329317269074;4.1841527196652715;4.184497907949792;4.159365904365906;4.041363636363637
16:39:24 INFO - PID 5696 | |6;480p.60fps.webm_scale_1_startup;3.4252111872146105;3.534004711425207;3.4691445086705195;3.5684066587395966;3.4368613974799556;3.496794871794874;3.4726620370370362;3.469202312138729;3.6415533980582495;3.501878646441074;3.457062211981566;3.452652474108172
16:39:24 INFO - PID 5696 | |7;480p.60fps.webm_scale_1_inclip;3.384407783417938;3.437139175257727;3.380076013513515;3.479417391304349;3.4493189655172407;3.4362886597938114;3.4015561224489823;3.368038720538723;3.5407522123893833;3.3909406779660998;3.3901864406779647;3.3728836424957827
16:39:24 INFO - PID 5696 | |8;480p.60fps.webm_scale_1.1_startup;5.475164233576641;5.55575925925926;5.682926136363634;5.578057620817842;5.516544117647059;5.7260782442748095;5.587811918063313;5.693605313092977;5.748984674329497;5.74840996168582;5.738183556405353;5.7601343570057555
16:39:24 INFO - PID 5696 | |9;480p.60fps.webm_scale_1.1_inclip;5.541246537396121;5.589594972067041;5.587681564245819;5.541551246537396;5.450681198910086;5.602941176470588;5.451498637602182;5.698675213675215;5.651200564971756;5.603963585434178;5.683309659090903;5.634464788732396
16:39:24 INFO - PID 5696 | |10;480p.60fps.webm_scale_2_startup;10.136418918918922;10.276044520547943;10.274520547945205;10.240733788395916;10.453780487804867;10.64198581560284;10.13591216216216;10.27433219178082;10.275051369863009;9.837393442622947;10.490227272727266;10.070151006711418
16:39:24 INFO - PID 5696 | |11;480p.60fps.webm_scale_2_inclip;10.204336734693873;9.95330845771145;10.257179487179476;10.104772727272723;10.104823232323238;10.153121827411171;9.85302955665025;10.259846153846144;9.479786729857823;10.587063492063482;9.807598039215685;10.000625
16:39:24 INFO - PID 5696 | |12;1080p.60fps.mp4_scale_1_startup;11.112555555555566;11.409220532319406;11.453854961832075;11.409904942965767;11.407376425855512;11.156524163568784;11.281729323308266;11.239382022471913;11.324566037735844;11.114870370370355;11.407148288973376;11.45314885496184
16:39:24 INFO - PID 5696 | |13;1080p.60fps.mp4_scale_1_inclip;10.698823529411767;10.93371584699451;10.872255434782584;10.990686813186814;10.758467741935508;10.752661290322555;10.812432432432448;10.700374331550808;10.87529891304348;10.581825396825417;10.696069518716582;10.815810810810786
16:39:24 INFO - PID 5696 | |14;1080p.60fps.mp4_scale_1.1_startup;12.876115879828303;12.712415254237277;12.825064102564081;12.823290598290605;12.76672340425532;12.662995780590705;12.767574468085096;13.045326086956534;12.55271966527196;12.609369747899185;12.988528138528164;12.773531914893612
16:39:24 INFO - PID 5696 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.1978353658537;12.348395061728409;12.425062111801273;12.424223602484444;12.426739130434793;12.125515151515145;12.506312500000012;12.352160493827133;12.050813253012034;12.503468749999957;12.271595092024521;12.346604938271614
16:39:24 INFO - PID 5696 | |16;1080p.60fps.mp4_scale_2_startup;11.812598425196828;11.583108108108114;11.906329365079353;11.451145038167928;11.280883458646603;11.673424124513618;11.496111111111114;11.58519305019303;11.954900398406346;11.765450980392165;11.721738281249998;11.676381322957205
16:39:24 INFO - PID 5696 | |17;1080p.60fps.mp4_scale_2_inclip;11.113666666666703;11.112638888888897;11.1125;11.051546961325977;11.238567415730317;11.116416666666636;11.17829608938548;10.75508064516129;11.111861111111123;11.241011235955023;10.994587912087908;11.30031073446327
16:39:24 INFO - PID 5696 | __end_tp_report
16:39:24 INFO - PID 5696 | __start_cc_report
16:39:24 INFO - PID 5696 | _x_x_mozilla_cycle_collect,543
16:39:24 INFO - PID 5696 | __end_cc_report
16:39:24 INFO - PID 5696 | __startTimestamp1508974764721__endTimestamp
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | ------- Summary: start -------
16:39:24 INFO - PID 5696 | Number of tests: 18
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.69 Median:1.69 stddev:0.00 (0.3%) stddev-sans-first:0.00
16:39:24 INFO - PID 5696 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
16:39:24 INFO - PID 5696 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.77 Median:1.78 stddev:0.02 (1.2%) stddev-sans-first:0.02
16:39:24 INFO - PID 5696 | Values: 1.8 1.7 1.8 1.7 1.8 1.8 1.7 1.8 1.8 1.8 1.8 1.7
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.76 stddev:0.02 (1.4%) stddev-sans-first:0.03
16:39:24 INFO - PID 5696 | Values: 1.8 1.7 1.8 1.7 1.8 1.7 1.7 1.8 1.8 1.7 1.8 1.7
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.12 Median:4.14 stddev:0.10 (2.3%) stddev-sans-first:0.07
16:39:24 INFO - PID 5696 | Values: 3.9 4.1 4.2 4.2 4.2 4.1 4.2 4.1 4.1 4.0 4.1 4.1
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.05 Median:4.10 stddev:0.15 (3.7%) stddev-sans-first:0.16
16:39:24 INFO - PID 5696 | Values: 4.0 3.7 4.1 4.1 4.2 4.1 3.8 4.0 4.2 4.2 4.2 4.0
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.49 Median:3.48 stddev:0.06 (1.8%) stddev-sans-first:0.06
16:39:24 INFO - PID 5696 | Values: 3.4 3.5 3.5 3.6 3.4 3.5 3.5 3.5 3.6 3.5 3.5 3.5
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.42 stddev:0.05 (1.5%) stddev-sans-first:0.05
16:39:24 INFO - PID 5696 | Values: 3.4 3.4 3.4 3.5 3.4 3.4 3.4 3.4 3.5 3.4 3.4 3.4
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.65 Median:5.71 stddev:0.10 (1.8%) stddev-sans-first:0.09
16:39:24 INFO - PID 5696 | Values: 5.5 5.6 5.7 5.6 5.5 5.7 5.6 5.7 5.7 5.7 5.7 5.8
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.59 Median:5.60 stddev:0.08 (1.4%) stddev-sans-first:0.08
16:39:24 INFO - PID 5696 | Values: 5.5 5.6 5.6 5.5 5.5 5.6 5.5 5.7 5.7 5.6 5.7 5.6
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.26 Median:10.28 stddev:0.21 (2.0%) stddev-sans-first:0.22
16:39:24 INFO - PID 5696 | Values: 10.1 10.3 10.3 10.2 10.5 10.6 10.1 10.3 10.3 9.8 10.5 10.1
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.06 Median:10.42 stddev:0.28 (2.7%) stddev-sans-first:0.29
16:39:24 INFO - PID 5696 | Values: 10.2 10.0 10.3 10.1 10.1 10.2 9.9 10.3 9.5 10.6 9.8 10.0
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.31 Median:11.41 stddev:0.13 (1.1%) stddev-sans-first:0.12
16:39:24 INFO - PID 5696 | Values: 11.1 11.4 11.5 11.4 11.4 11.2 11.3 11.2 11.3 11.1 11.4 11.5
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.79 Median:10.81 stddev:0.12 (1.1%) stddev-sans-first:0.12
16:39:24 INFO - PID 5696 | Values: 10.7 10.9 10.9 11.0 10.8 10.8 10.8 10.7 10.9 10.6 10.7 10.8
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.78 Median:12.80 stddev:0.14 (1.1%) stddev-sans-first:0.15
16:39:24 INFO - PID 5696 | Values: 12.9 12.7 12.8 12.8 12.8 12.7 12.8 13.0 12.6 12.6 13.0 12.8
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.33 Median:12.39 stddev:0.14 (1.2%) stddev-sans-first:0.15
16:39:24 INFO - PID 5696 | Values: 12.2 12.3 12.4 12.4 12.4 12.1 12.5 12.4 12.1 12.5 12.3 12.3
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.66 Median:11.70 stddev:0.19 (1.7%) stddev-sans-first:0.20
16:39:24 INFO - PID 5696 | Values: 11.8 11.6 11.9 11.5 11.3 11.7 11.5 11.6 12.0 11.8 11.7 11.7
16:39:24 INFO - PID 5696 |
16:39:24 INFO - PID 5696 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.11 Median:11.12 stddev:0.14 (1.3%) stddev-sans-first:0.15
16:39:24 INFO - PID 5696 | Values: 11.1 11.1 11.1 11.1 11.2 11.1 11.2 10.8 11.1 11.2 11.0 11.3
16:39:24 INFO - PID 5696 | -------- Summary: end --------
16:39:24 INFO - PID 5696 |
16:39:25 INFO - TEST-INFO | 5696: exit 0
16:39:25 INFO - TEST-OK | basic_compositor_video | took 560697ms
16:39:25 INFO - TEST-START | glvideo
16:39:25 INFO - Initialising browser for glvideo test...
16:39:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46180/getInfo.html -profile /tmp/tmpssiswF/profile
16:39:25 INFO - TEST-INFO | started process 6975 (/builds/slave/test/build/application/firefox/firefox http://localhost:46180/getInfo.html)
16:39:30 INFO - TEST-INFO | 6975: exit 0
16:39:30 INFO - Browser initialized.
16:39:30 INFO - Running cycle 1/1 for glvideo test...
16:39:30 INFO - Using env: {'DISPLAY': ':0',
16:39:30 INFO - 'HOME': '/home/cltbld',
16:39:30 INFO - 'JSGC_DISABLE_POISONING': '1',
16:39:30 INFO - 'LANG': 'en_US.UTF-8',
16:39:30 INFO - 'LANGUAGE': 'en_US:en',
16:39:30 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:39:30 INFO - 'LOGNAME': 'cltbld',
16:39:30 INFO - 'MAIL': '/var/mail/cltbld',
16:39:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:39:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:39:30 INFO - 'MOZ_CRASHREPORTER': '1',
16:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:39:30 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:39:30 INFO - 'MOZ_NO_REMOTE': '1',
16:39:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:39:30 INFO - 'NO_EM_RESTART': '1',
16:39:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:39:30 INFO - 'PWD': '/builds/slave/test',
16:39:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:39:30 INFO - 'RUST_BACKTRACE': 'full',
16:39:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:39:30 INFO - 'SHELL': '/bin/bash',
16:39:30 INFO - 'SHLVL': '1',
16:39:30 INFO - 'STYLO_FORCE_DISABLED': '1',
16:39:30 INFO - 'TERM': 'linux',
16:39:30 INFO - 'TMOUT': '86400',
16:39:30 INFO - 'USER': 'cltbld',
16:39:30 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044',
16:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:39:30 INFO - '_': '/tools/buildbot/bin/python'}
16:39:30 INFO - TEST-INFO | started process 7249 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpssiswF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
16:39:31 INFO - PID 7249 |
16:39:31 INFO - PID 7249 | (/builds/slave/test/build/application/firefox/firefox:7300): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:39:31 INFO - PID 7249 |
16:39:32 INFO - PID 7249 | [talos glvideo result] Mean tick time across 100 ticks: 8.535050000000002 ms
16:39:32 INFO - PID 7249 | Cycle 1(1): loaded http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html)
16:39:34 INFO - PID 7249 | [talos glvideo result] Mean tick time across 100 ticks: 6.46335 ms
16:39:34 INFO - PID 7249 | Cycle 1(2): loaded http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html)
16:39:35 INFO - PID 7249 | [talos glvideo result] Mean tick time across 100 ticks: 6.5422 ms
16:39:35 INFO - PID 7249 | Cycle 1(3): loaded http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html)
16:39:37 INFO - PID 7249 | [talos glvideo result] Mean tick time across 100 ticks: 6.6053500000000005 ms
16:39:37 INFO - PID 7249 | Cycle 1(4): loaded http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html)
16:39:38 INFO - PID 7249 | [talos glvideo result] Mean tick time across 100 ticks: 6.61195 ms
16:39:38 INFO - PID 7249 | Cycle 1(5): loaded http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46180/tests/webgl/benchmarks/video/video_upload.html)
16:39:38 INFO - PID 7249 | __start_tp_report
16:39:38 INFO - PID 7249 | _x_x_mozilla_page_load
16:39:38 INFO - PID 7249 | _x_x_mozilla_page_load_details
16:39:38 INFO - PID 7249 | |i|pagename|runs|
16:39:38 INFO - PID 7249 | |0;Mean tick time across 100 ticks: ;8.535050000000002;6.46335;6.5422;6.6053500000000005;6.61195
16:39:38 INFO - PID 7249 | __end_tp_report
16:39:38 INFO - PID 7249 | __start_cc_report
16:39:38 INFO - PID 7249 | _x_x_mozilla_cycle_collect,280
16:39:38 INFO - PID 7249 | __end_cc_report
16:39:38 INFO - PID 7249 | __startTimestamp1508974778562__endTimestamp
16:39:38 INFO - PID 7249 |
16:39:38 INFO - PID 7249 | ------- Summary: start -------
16:39:38 INFO - PID 7249 | Number of tests: 1
16:39:38 INFO - PID 7249 |
16:39:38 INFO - PID 7249 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.95 Median:6.61 stddev:0.89 (13.4%) stddev-sans-first:0.07
16:39:38 INFO - PID 7249 | Values: 8.5 6.5 6.5 6.6 6.6
16:39:38 INFO - PID 7249 | -------- Summary: end --------
16:39:38 INFO - PID 7249 |
16:39:38 INFO - PID 7249 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
16:39:39 INFO - TEST-INFO | 7249: exit 0
16:39:39 INFO - TEST-OK | glvideo | took 13844ms
16:39:39 INFO - SUITE-END | took 574s
16:39:39 INFO - Completed test suite (00:09:35)
16:39:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.649573914143853, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6791634023503077, 1.6822057174887894, 1.6932308126410833, 1.686683530073075, 1.6865345699831367, 1.68001679731243, 1.6867594154019112, 1.680025195968645, 1.6865008431703203, 1.6819198430493274, 1.6875590551181103, 1.6944466403162055], "value": 1.6865345699831367, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6702587646076792, 1.6713408521303257, 1.6682902418682237, 1.6714076858813698, 1.6671541666666663, 1.668940783986655, 1.6689491242702257, 1.6684945788156795, 1.6673208333333331, 1.6705676126878128, 1.6653288925895082, 1.6713199665831242], "value": 1.668940783986655, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7690801886792455, 1.7494897959183673, 1.7795788849347567, 1.742746806039489, 1.7967095808383233, 1.7879231227651968, 1.7445029069767441, 1.7741957421643997, 1.7986750599520387, 1.7850892325996428, 1.7620845566647092, 1.7387369640787946], "value": 1.7741957421643997, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7660723742277133, 1.7201461736887367, 1.7770870337477807, 1.7307093425605535, 1.7778888888888889, 1.7476026200873362, 1.7380017376194619, 1.7591468777484613, 1.7907430617726046, 1.7430095818815325, 1.782762923351158, 1.722906976744187], "value": 1.7476026200873362, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.8969350649350667, 4.122087912087913, 4.208632538569423, 4.16128294036061, 4.208793828892004, 4.076277173913045, 4.244108910891088, 4.144516574585637, 4.132679063360882, 3.9897805851063834, 4.14493093922652, 4.116488340192044], "value": 4.144516574585637, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9780616302186846, 3.717983271375464, 4.100245901639345, 4.108049281314166, 4.212652631578948, 4.091860940695293, 3.8101142857142847, 4.017329317269074, 4.1841527196652715, 4.184497907949792, 4.159365904365906, 4.041363636363637], "value": 4.100245901639345, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4252111872146105, 3.534004711425207, 3.4691445086705195, 3.5684066587395966, 3.4368613974799556, 3.496794871794874, 3.4726620370370362, 3.469202312138729, 3.6415533980582495, 3.501878646441074, 3.457062211981566, 3.452652474108172], "value": 3.4726620370370362, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.384407783417938, 3.437139175257727, 3.380076013513515, 3.479417391304349, 3.4493189655172407, 3.4362886597938114, 3.4015561224489823, 3.368038720538723, 3.5407522123893833, 3.3909406779660998, 3.3901864406779647, 3.3728836424957827], "value": 3.4015561224489823, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.475164233576641, 5.55575925925926, 5.682926136363634, 5.578057620817842, 5.516544117647059, 5.7260782442748095, 5.587811918063313, 5.693605313092977, 5.748984674329497, 5.74840996168582, 5.738183556405353, 5.7601343570057555], "value": 5.693605313092977, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.541246537396121, 5.589594972067041, 5.587681564245819, 5.541551246537396, 5.450681198910086, 5.602941176470588, 5.451498637602182, 5.698675213675215, 5.651200564971756, 5.603963585434178, 5.683309659090903, 5.634464788732396], "value": 5.602941176470588, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.136418918918922, 10.276044520547943, 10.274520547945205, 10.240733788395916, 10.453780487804867, 10.64198581560284, 10.13591216216216, 10.27433219178082, 10.275051369863009, 9.837393442622947, 10.490227272727266, 10.070151006711418], "value": 10.274520547945205, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.204336734693873, 9.95330845771145, 10.257179487179476, 10.104772727272723, 10.104823232323238, 10.153121827411171, 9.85302955665025, 10.259846153846144, 9.479786729857823, 10.587063492063482, 9.807598039215685, 10.000625], "value": 10.104772727272723, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.112555555555566, 11.409220532319406, 11.453854961832075, 11.409904942965767, 11.407376425855512, 11.156524163568784, 11.281729323308266, 11.239382022471913, 11.324566037735844, 11.114870370370355, 11.407148288973376, 11.45314885496184], "value": 11.407148288973376, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.698823529411767, 10.93371584699451, 10.872255434782584, 10.990686813186814, 10.758467741935508, 10.752661290322555, 10.812432432432448, 10.700374331550808, 10.87529891304348, 10.581825396825417, 10.696069518716582, 10.815810810810786], "value": 10.812432432432448, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.876115879828303, 12.712415254237277, 12.825064102564081, 12.823290598290605, 12.76672340425532, 12.662995780590705, 12.767574468085096, 13.045326086956534, 12.55271966527196, 12.609369747899185, 12.988528138528164, 12.773531914893612], "value": 12.767574468085096, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.1978353658537, 12.348395061728409, 12.425062111801273, 12.424223602484444, 12.426739130434793, 12.125515151515145, 12.506312500000012, 12.352160493827133, 12.050813253012034, 12.503468749999957, 12.271595092024521, 12.346604938271614], "value": 12.352160493827133, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.812598425196828, 11.583108108108114, 11.906329365079353, 11.451145038167928, 11.280883458646603, 11.673424124513618, 11.496111111111114, 11.58519305019303, 11.954900398406346, 11.765450980392165, 11.721738281249998, 11.676381322957205], "value": 11.673424124513618, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.113666666666703, 11.112638888888897, 11.1125, 11.051546961325977, 11.238567415730317, 11.116416666666636, 11.17829608938548, 10.75508064516129, 11.111861111111123, 11.241011235955023, 10.994587912087908, 11.30031073446327], "value": 11.112638888888897, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8.535050000000002, 6.46335, 6.5422, 6.6053500000000005, 6.61195], "value": 6.573775, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
16:39:39 INFO - Return code: 0
16:39:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
16:39:39 INFO - # TBPL SUCCESS #
16:39:39 INFO - Running post-action listener: _package_coverage_data
16:39:39 INFO - Running post-action listener: _resource_record_post_action
16:39:39 INFO - [mozharness: 2017-10-25 23:39:39.743590Z] Finished run-tests step (success)
16:39:39 INFO - Running post-run listener: _resource_record_post_run
16:39:39 INFO - Total resource usage - Wall time: 594s; CPU: 38.0%; Read bytes: 8921088; Write bytes: 433045504; Read time: 704; Write time: 339228
16:39:39 INFO - TinderboxPrint: CPU usage
38.4%
16:39:39 INFO - TinderboxPrint: I/O read bytes / time
8,921,088 / 704
16:39:39 INFO - TinderboxPrint: I/O write bytes / time
433,045,504 / 339,228
16:39:39 INFO - TinderboxPrint: CPU idle
2,868.8 (61.6%)
16:39:39 INFO - TinderboxPrint: CPU system
302.6 (6.5%)
16:39:39 INFO - TinderboxPrint: CPU user
1,472.8 (31.6%)
16:39:39 INFO - TinderboxPrint: Swap in / out
0 / 0
16:39:39 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 127635456; Read time: 0; Write time: 114496
16:39:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
16:39:39 INFO - run-tests - Wall time: 576s; CPU: 39.0%; Read bytes: 8921088; Write bytes: 305410048; Read time: 704; Write time: 224732
16:39:40 INFO - Running post-run listener: _upload_blobber_files
16:39:40 INFO - Blob upload gear active.
16:39:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
16:39:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
16:39:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
16:39:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
16:39:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:334: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:40 INFO - SNIMissingWarning
16:39:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:40 INFO - InsecurePlatformWarning
16:39:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:39:40 INFO - SubjectAltNameWarning
16:39:40 INFO - (blobuploader) - INFO - Open directory for files ...
16:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
16:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:39:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:40 INFO - InsecurePlatformWarning
16:39:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:39:40 INFO - SubjectAltNameWarning
16:39:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:41 INFO - InsecurePlatformWarning
16:39:41 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
16:39:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:39:41 INFO - (blobuploader) - INFO - Done attempting.
16:39:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
16:39:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:39:41 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:39:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:41 INFO - InsecurePlatformWarning
16:39:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:39:41 INFO - SubjectAltNameWarning
16:39:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:43 INFO - InsecurePlatformWarning
16:39:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
16:39:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:39:44 INFO - (blobuploader) - INFO - Done attempting.
16:39:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
16:39:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:39:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:39:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:44 INFO - InsecurePlatformWarning
16:39:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:39:44 INFO - SubjectAltNameWarning
16:39:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:44 INFO - InsecurePlatformWarning
16:39:44 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
16:39:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:39:44 INFO - (blobuploader) - INFO - Done attempting.
16:39:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
16:39:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
16:39:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
16:39:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:44 INFO - InsecurePlatformWarning
16:39:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
16:39:45 INFO - SubjectAltNameWarning
16:39:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
16:39:45 INFO - InsecurePlatformWarning
16:39:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
16:39:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
16:39:45 INFO - (blobuploader) - INFO - Done attempting.
16:39:45 INFO - (blobuploader) - INFO - Iteration through files over.
16:39:45 INFO - Return code: 0
16:39:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
16:39:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
16:39:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a2a5e9ca12c686d3fdda49f7d3dfc93254c1fc83202ad5908f205f6907a11934c885f4761469d3c569cd955199bfff9b07db1382597113d187b37d84dc81a3", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/194ca82aad175a6691b0246018b5a3b6eef41cda9627af98a63a01fb0923002560a8dcef2368129de23368eb6d70373054d5cc894f82991e7c61c22040e8625d", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a7ccaf7c65a8d70f04fc5a9994f3384b47b8903b25992ed58d46070ec7e91d4fcfad2106cdcf151a73098307b32299307c65fd102f5b0955e97400f59fd84b6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d19a1a5e558f01e61302ad9db6c9df5d409f1dbc33da16690fa19dd8c49a8ddf8bab7908b7b5dbcb1d63feb0c191427ce27823cae3200239bc5a624d5b86a88e"}
16:39:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
16:39:45 INFO - Writing to file /builds/slave/test/properties/blobber_files
16:39:45 INFO - Contents:
16:39:45 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a2a5e9ca12c686d3fdda49f7d3dfc93254c1fc83202ad5908f205f6907a11934c885f4761469d3c569cd955199bfff9b07db1382597113d187b37d84dc81a3", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/194ca82aad175a6691b0246018b5a3b6eef41cda9627af98a63a01fb0923002560a8dcef2368129de23368eb6d70373054d5cc894f82991e7c61c22040e8625d", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a7ccaf7c65a8d70f04fc5a9994f3384b47b8903b25992ed58d46070ec7e91d4fcfad2106cdcf151a73098307b32299307c65fd102f5b0955e97400f59fd84b6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d19a1a5e558f01e61302ad9db6c9df5d409f1dbc33da16690fa19dd8c49a8ddf8bab7908b7b5dbcb1d63feb0c191427ce27823cae3200239bc5a624d5b86a88e"}
16:39:45 INFO - Running post-run listener: copy_logs_to_upload_dir
16:39:45 INFO - Copying logs to upload dir...
16:39:45 INFO - mkdir: /builds/slave/test/build/upload/logs
16:39:45 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=650.711858
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 50 secs) (at 2017-10-25 16:39:45.954006) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-25 16:39:45.961687) =========
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=3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a2a5e9ca12c686d3fdda49f7d3dfc93254c1fc83202ad5908f205f6907a11934c885f4761469d3c569cd955199bfff9b07db1382597113d187b37d84dc81a3", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/194ca82aad175a6691b0246018b5a3b6eef41cda9627af98a63a01fb0923002560a8dcef2368129de23368eb6d70373054d5cc894f82991e7c61c22040e8625d", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a7ccaf7c65a8d70f04fc5a9994f3384b47b8903b25992ed58d46070ec7e91d4fcfad2106cdcf151a73098307b32299307c65fd102f5b0955e97400f59fd84b6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d19a1a5e558f01e61302ad9db6c9df5d409f1dbc33da16690fa19dd8c49a8ddf8bab7908b7b5dbcb1d63feb0c191427ce27823cae3200239bc5a624d5b86a88e"}
build_url:https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011361
build_url: 'https://queue.taskcluster.net/v1/task/J9_dg7rvT3iO4Ce00dRMbA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f6a2a5e9ca12c686d3fdda49f7d3dfc93254c1fc83202ad5908f205f6907a11934c885f4761469d3c569cd955199bfff9b07db1382597113d187b37d84dc81a3", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/194ca82aad175a6691b0246018b5a3b6eef41cda9627af98a63a01fb0923002560a8dcef2368129de23368eb6d70373054d5cc894f82991e7c61c22040e8625d", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7a7ccaf7c65a8d70f04fc5a9994f3384b47b8903b25992ed58d46070ec7e91d4fcfad2106cdcf151a73098307b32299307c65fd102f5b0955e97400f59fd84b6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d19a1a5e558f01e61302ad9db6c9df5d409f1dbc33da16690fa19dd8c49a8ddf8bab7908b7b5dbcb1d63feb0c191427ce27823cae3200239bc5a624d5b86a88e"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-25 16:39:45.996970) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 16:39:45.997369) =========
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=3a3620fb28aea1604ab609ef000001d2-1508973557.819927-1422450044
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.005050
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 16:39:46.080140) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-25 16:39:46.080553) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-25 16:39:46.080929) =========
========= Total master_lag: 0.26 =========