builder: mozilla-central_ubuntu64_hw_test-g4-e10s-pgo
slave: talos-linux64-ix-041
starttime: 1509185576.64
results: success (0)
revision: c16bc8097c102cdd2b70d8e7db3656222455a6ed
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.639065) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.639793) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.640069) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.701789) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.702457) =========
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=8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-28 03:12:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 222M=0s
2017-10-28 03:12:56 (222 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.145790
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.881756) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.882377) =========
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=8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020279
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 03:12:56.937678) =========
========= Started 'bash -c ...' (results: 0, elapsed: 15 secs) (at 2017-10-28 03:12:56.941091) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c16bc8097c102cdd2b70d8e7db3656222455a6ed --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 c16bc8097c102cdd2b70d8e7db3656222455a6ed --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=8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-28 03:12:56,995 truncating revision to first 12 chars
2017-10-28 03:12:56,995 Setting DEBUG logging.
2017-10-28 03:12:56,995 attempt 1/10
2017-10-28 03:12:56,995 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c16bc8097c10?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-28 03:12:57,435 unpacking tar archive at: mozilla-central-c16bc8097c10/testing/mozharness/
program finished with exit code 0
elapsedTime=15.198498
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 15 secs) (at 2017-10-28 03:13:12.157298) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-28 03:13:12.157613) =========
script_repo_revision: c16bc8097c102cdd2b70d8e7db3656222455a6ed
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-28 03:13:12.157981) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-28 03:13:12.158266) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-28 03:13:12.172877) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 18 secs) (at 2017-10-28 03:13:12.173279) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:13:12 INFO - MultiFileLogger online at 20171028 03:13:12 in /builds/slave/test
03:13:12 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
03:13:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:13:12 INFO - {'append_to_log': False,
03:13:12 INFO - 'base_work_dir': '/builds/slave/test',
03:13:12 INFO - 'blob_upload_branch': 'Firefox',
03:13:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:13:12 INFO - 'branch': 'Firefox',
03:13:12 INFO - 'buildbot_json_path': 'buildprops.json',
03:13:12 INFO - 'code_coverage': False,
03:13:12 INFO - 'config_files': ('talos/linux_config.py',),
03:13:12 INFO - 'default_actions': ('clobber',
03:13:12 INFO - 'read-buildbot-config',
03:13:12 INFO - 'download-and-extract',
03:13:12 INFO - 'populate-webroot',
03:13:12 INFO - 'create-virtualenv',
03:13:12 INFO - 'install',
03:13:12 INFO - 'setup-mitmproxy',
03:13:12 INFO - 'run-tests'),
03:13:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:13:12 INFO - 'disable_ccov_upload': False,
03:13:12 INFO - 'disable_stylo': False,
03:13:12 INFO - 'download_minidump_stackwalk': True,
03:13:12 INFO - 'download_symbols': 'ondemand',
03:13:12 INFO - 'enable_stylo': False,
03:13:12 INFO - 'enable_webrender': False,
03:13:12 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:13:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:13:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:13:12 INFO - 'gecko_profile': False,
03:13:12 INFO - 'gecko_profile_interval': 0,
03:13:12 INFO - 'installer_path': 'installer.exe',
03:13:12 INFO - 'log_level': 'info',
03:13:12 INFO - 'log_name': 'talos',
03:13:12 INFO - 'log_to_console': True,
03:13:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:13:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:13:12 INFO - 'opt_config_files': (),
03:13:12 INFO - 'pip_index': False,
03:13:12 INFO - 'suite': 'g4-e10s',
03:13:12 INFO - 'system_bits': '32',
03:13:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:13:12 INFO - 'title': 'talos-linux64-ix-041',
03:13:12 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:13:12 INFO - 'use_talos_json': True,
03:13:12 INFO - 'verify': 'False',
03:13:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:13:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:13:12 INFO - 'work_dir': 'build'}
03:13:12 INFO - [mozharness: 2017-10-28 10:13:12.363859Z] Running clobber step.
03:13:12 INFO - Running pre-action listener: _resource_record_pre_action
03:13:12 INFO - Running main action method: clobber
03:13:12 INFO - rmtree: /builds/slave/test/build
03:13:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:13:13 INFO - Running post-action listener: _resource_record_post_action
03:13:13 INFO - [mozharness: 2017-10-28 10:13:13.442948Z] Finished clobber step (success)
03:13:13 INFO - [mozharness: 2017-10-28 10:13:13.443043Z] Running read-buildbot-config step.
03:13:13 INFO - Running pre-action listener: _resource_record_pre_action
03:13:13 INFO - Running main action method: read_buildbot_config
03:13:13 INFO - Using buildbot properties:
03:13:13 INFO - {
03:13:13 INFO - "project": "",
03:13:13 INFO - "product": "firefox",
03:13:13 INFO - "who": "archaeopteryx@coole-files.de",
03:13:13 INFO - "installer_path": "public/build/target.tar.bz2",
03:13:13 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
03:13:13 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:13:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-e10s",
03:13:13 INFO - "stage_platform": "linux64-pgo",
03:13:13 INFO - "basedir": "/builds/slave/test",
03:13:13 INFO - "buildnumber": 79,
03:13:13 INFO - "platform": "ubuntu64_hw",
03:13:13 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
03:13:13 INFO - "slavebuilddir": "test",
03:13:13 INFO - "taskId": "Lm50jG2aTUuIuj2zDYR1zQ",
03:13:13 INFO - "branch": "mozilla-central",
03:13:13 INFO - "script_repo_revision": "production",
03:13:13 INFO - "revision": "c16bc8097c102cdd2b70d8e7db3656222455a6ed",
03:13:13 INFO - "slavename": "talos-linux64-ix-041",
03:13:13 INFO - "repo_path": "mozilla-central"
03:13:13 INFO - }
03:13:13 INFO - Finding installer, test and symbols from parent task.
03:13:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lm50jG2aTUuIuj2zDYR1zQ'}, attempt #1
03:13:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lm50jG2aTUuIuj2zDYR1zQ'}, attempt #1
03:13:14 INFO - Task dependencies: cHXMObpVQ5mn11leOXY0zQ
03:13:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ'}, attempt #1
03:13:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2
03:13:14 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.test_packages.json
03:13:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.crashreporter-symbols.zip
03:13:14 INFO - Running post-action listener: _resource_record_post_action
03:13:14 INFO - [mozharness: 2017-10-28 10:13:14.786460Z] Finished read-buildbot-config step (success)
03:13:14 INFO - [mozharness: 2017-10-28 10:13:14.786718Z] Running download-and-extract step.
03:13:14 INFO - Running pre-action listener: _resource_record_pre_action
03:13:14 INFO - Running main action method: download_and_extract
03:13:14 INFO - mkdir: /builds/slave/test/build/tests
03:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:14 INFO - trying https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.test_packages.json
03:13:14 INFO - Downloading https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:13:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:13:15 INFO - Downloaded 989 bytes.
03:13:15 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:13:15 INFO - Using the following test package requirements:
03:13:15 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:13:15 INFO - u'common': [u'target.common.tests.zip'],
03:13:15 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:13:15 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:13:15 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:13:15 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:13:15 INFO - u'mozbase': [u'target.common.tests.zip'],
03:13:15 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:13:15 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:13:15 INFO - u'web-platform': [u'target.common.tests.zip',
03:13:15 INFO - u'target.web-platform.tests.tar.gz'],
03:13:15 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:13:15 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:13:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.common.tests.zip
03:13:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:13:15 INFO - Fetch https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.common.tests.zip into memory
03:13:32 INFO - Content-Length response header: 56889847
03:13:32 INFO - Bytes received: 56889847
03:13:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.talos.tests.zip
03:13:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:13:43 INFO - Fetch https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.talos.tests.zip into memory
03:13:47 INFO - Content-Length response header: 18770379
03:13:47 INFO - Bytes received: 18770379
03:13:47 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')]}
03:13:47 INFO - trying https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2
03:13:47 INFO - Downloading https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:13:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:13:56 INFO - Downloaded 63545291 bytes.
03:13:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2
03:13:56 INFO - mkdir: /builds/slave/test/properties
03:13:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:13:56 INFO - Writing to file /builds/slave/test/properties/build_url
03:13:56 INFO - Contents:
03:13:56 INFO - build_url:https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2
03:13:56 INFO - Running post-action listener: _resource_record_post_action
03:13:56 INFO - Running post-action listener: find_tests_for_verification
03:13:56 INFO - Running post-action listener: set_extra_try_arguments
03:13:56 INFO - [mozharness: 2017-10-28 10:13:56.214554Z] Finished download-and-extract step (success)
03:13:56 INFO - [mozharness: 2017-10-28 10:13:56.214792Z] Running populate-webroot step.
03:13:56 INFO - Running pre-action listener: _resource_record_pre_action
03:13:56 INFO - Running main action method: populate_webroot
03:13:56 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:13:56 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['tresize',
03:13:56 INFO - 'tcanvasmark']},
03:13:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:13:56 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['dromaeo_css',
03:13:56 INFO - 'kraken']},
03:13:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:13:56 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:13:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'tests': ['damp', 'tps']},
03:13:56 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['damp', 'tps']},
03:13:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:13:56 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['dromaeo_dom']},
03:13:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
03:13:56 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['basic_compositor_video',
03:13:56 INFO - 'glvideo']},
03:13:56 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:13:56 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['ts_paint_webext',
03:13:56 INFO - 'tp5o_webext']},
03:13:56 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:13:56 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:56 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:56 INFO - 'talos_options': ['--mitmproxy',
03:13:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:56 INFO - '--firstNonBlankPaint'],
03:13:56 INFO - 'tests': ['tp6_google_heavy',
03:13:56 INFO - 'tp6_youtube_heavy',
03:13:56 INFO - 'tp6_amazon_heavy',
03:13:56 INFO - 'tp6_facebook_heavy']},
03:13:56 INFO - 'other-e10s': {'tests': ['a11yr',
03:13:56 INFO - 'ts_paint',
03:13:56 INFO - 'tpaint',
03:13:56 INFO - 'sessionrestore',
03:13:56 INFO - 'sessionrestore_many_windows',
03:13:56 INFO - 'sessionrestore_no_auto_restore',
03:13:56 INFO - 'tabpaint',
03:13:56 INFO - 'cpstartup']},
03:13:56 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['a11yr',
03:13:56 INFO - 'ts_paint',
03:13:56 INFO - 'tpaint',
03:13:56 INFO - 'sessionrestore',
03:13:56 INFO - 'sessionrestore_many_windows',
03:13:56 INFO - 'sessionrestore_no_auto_restore',
03:13:56 INFO - 'tabpaint',
03:13:56 INFO - 'cpstartup']},
03:13:56 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:13:56 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:13:56 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['perf_reftest_singletons']},
03:13:56 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['perf_reftest']},
03:13:56 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:13:56 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['speedometer']},
03:13:56 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:13:56 INFO - 'tsvgr_opacity',
03:13:56 INFO - 'tart',
03:13:56 INFO - 'tscrollx',
03:13:56 INFO - 'tsvg_static']},
03:13:56 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['tsvgx',
03:13:56 INFO - 'tsvgr_opacity',
03:13:56 INFO - 'tart',
03:13:56 INFO - 'tscrollx',
03:13:56 INFO - 'tsvg_static']},
03:13:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:13:56 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'talos_options': ['--disable-stylo'],
03:13:56 INFO - 'tests': ['tp5o']},
03:13:56 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:56 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:56 INFO - 'talos_options': ['--mitmproxy',
03:13:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:56 INFO - '--firstNonBlankPaint'],
03:13:56 INFO - 'tests': ['tp6_google',
03:13:56 INFO - 'tp6_youtube',
03:13:56 INFO - 'tp6_amazon',
03:13:56 INFO - 'tp6_facebook']},
03:13:56 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:56 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:56 INFO - 'talos_options': ['--disable-stylo',
03:13:56 INFO - '--mitmproxy',
03:13:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:56 INFO - '--firstNonBlankPaint'],
03:13:56 INFO - 'tests': ['tp6_google',
03:13:56 INFO - 'tp6_youtube',
03:13:56 INFO - 'tp6_amazon',
03:13:56 INFO - 'tp6_facebook']},
03:13:56 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:56 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:56 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:56 INFO - 'talos_options': ['--stylo-threads=1',
03:13:56 INFO - '--mitmproxy',
03:13:56 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:56 INFO - '--firstNonBlankPaint'],
03:13:56 INFO - 'tests': ['tp6_google',
03:13:56 INFO - 'tp6_youtube',
03:13:56 INFO - 'tp6_amazon',
03:13:56 INFO - 'tp6_facebook']},
03:13:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'talos_options': ['--xperf_path',
03:13:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:13:56 INFO - 'tests': ['tp5n']},
03:13:56 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:56 INFO - 'talos_options': ['--disable-stylo',
03:13:56 INFO - '--xperf_path',
03:13:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:13:56 INFO - 'tests': ['tp5n']}}}
03:13:56 INFO - Running post-action listener: _resource_record_post_action
03:13:56 INFO - [mozharness: 2017-10-28 10:13:56.235438Z] Finished populate-webroot step (success)
03:13:56 INFO - [mozharness: 2017-10-28 10:13:56.235526Z] Running create-virtualenv step.
03:13:56 INFO - Running pre-action listener: _resource_record_pre_action
03:13:56 INFO - Running main action method: create_virtualenv
03:13:56 INFO - Creating virtualenv /builds/slave/test/build/venv
03:13:56 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
03:13:56 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:13:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:13:56 INFO - Using real prefix '/usr'
03:13:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:13:56 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:13:58 INFO - Installing setuptools, pip, wheel...done.
03:13:58 INFO - Return code: 0
03:13:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:58 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:13:58 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
03:13:58 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
03:13:58 INFO - Using env: {'DISPLAY': ':0',
03:13:58 INFO - 'HOME': '/home/cltbld',
03:13:58 INFO - 'LANG': 'en_US.UTF-8',
03:13:58 INFO - 'LANGUAGE': 'en_US:en',
03:13:58 INFO - 'LOGNAME': 'cltbld',
03:13:58 INFO - 'MAIL': '/var/mail/cltbld',
03:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:58 INFO - 'MOZ_NO_REMOTE': '1',
03:13:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:58 INFO - 'NO_EM_RESTART': '1',
03:13:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:58 INFO - 'PWD': '/builds/slave/test',
03:13:58 INFO - 'SHELL': '/bin/bash',
03:13:58 INFO - 'SHLVL': '1',
03:13:58 INFO - 'TERM': 'linux',
03:13:58 INFO - 'TMOUT': '86400',
03:13:58 INFO - 'USER': 'cltbld',
03:13:58 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:58 INFO - '_': '/tools/buildbot/bin/python'}
03:13:58 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:13:58 INFO - Return code: 0
03:13:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:58 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:13:58 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
03:13:58 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
03:13:58 INFO - Using env: {'DISPLAY': ':0',
03:13:58 INFO - 'HOME': '/home/cltbld',
03:13:58 INFO - 'LANG': 'en_US.UTF-8',
03:13:58 INFO - 'LANGUAGE': 'en_US:en',
03:13:58 INFO - 'LOGNAME': 'cltbld',
03:13:58 INFO - 'MAIL': '/var/mail/cltbld',
03:13:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:58 INFO - 'MOZ_NO_REMOTE': '1',
03:13:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:58 INFO - 'NO_EM_RESTART': '1',
03:13:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:58 INFO - 'PWD': '/builds/slave/test',
03:13:58 INFO - 'SHELL': '/bin/bash',
03:13:58 INFO - 'SHLVL': '1',
03:13:58 INFO - 'TERM': 'linux',
03:13:58 INFO - 'TMOUT': '86400',
03:13:58 INFO - 'USER': 'cltbld',
03:13:58 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:13:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:58 INFO - '_': '/tools/buildbot/bin/python'}
03:13:59 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:59 INFO - Collecting psutil>=3.1.1
03:14:00 INFO - Installing collected packages: psutil
03:14:00 INFO - Successfully installed psutil-3.1.1
03:14:00 INFO - Return code: 0
03:14:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:00 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:00 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
03:14:00 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
03:14:00 INFO - Using env: {'DISPLAY': ':0',
03:14:00 INFO - 'HOME': '/home/cltbld',
03:14:00 INFO - 'LANG': 'en_US.UTF-8',
03:14:00 INFO - 'LANGUAGE': 'en_US:en',
03:14:00 INFO - 'LOGNAME': 'cltbld',
03:14:00 INFO - 'MAIL': '/var/mail/cltbld',
03:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:00 INFO - 'MOZ_NO_REMOTE': '1',
03:14:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:00 INFO - 'NO_EM_RESTART': '1',
03:14:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:00 INFO - 'PWD': '/builds/slave/test',
03:14:00 INFO - 'SHELL': '/bin/bash',
03:14:00 INFO - 'SHLVL': '1',
03:14:00 INFO - 'TERM': 'linux',
03:14:00 INFO - 'TMOUT': '86400',
03:14:00 INFO - 'USER': 'cltbld',
03:14:00 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:00 INFO - '_': '/tools/buildbot/bin/python'}
03:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:01 INFO - Collecting mozsystemmonitor==0.3
03:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:14:02 INFO - Installing collected packages: mozsystemmonitor
03:14:02 INFO - Successfully installed mozsystemmonitor-0.3
03:14:02 INFO - Return code: 0
03:14:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:14:02 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')]}
03:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14:02 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')]}
03:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:02 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:02 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
03:14:02 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
03:14:02 INFO - Using env: {'DISPLAY': ':0',
03:14:02 INFO - 'HOME': '/home/cltbld',
03:14:02 INFO - 'LANG': 'en_US.UTF-8',
03:14:02 INFO - 'LANGUAGE': 'en_US:en',
03:14:02 INFO - 'LOGNAME': 'cltbld',
03:14:02 INFO - 'MAIL': '/var/mail/cltbld',
03:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:02 INFO - 'MOZ_NO_REMOTE': '1',
03:14:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:02 INFO - 'NO_EM_RESTART': '1',
03:14:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:02 INFO - 'PWD': '/builds/slave/test',
03:14:02 INFO - 'SHELL': '/bin/bash',
03:14:02 INFO - 'SHLVL': '1',
03:14:02 INFO - 'TERM': 'linux',
03:14:02 INFO - 'TMOUT': '86400',
03:14:02 INFO - 'USER': 'cltbld',
03:14:02 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:02 INFO - '_': '/tools/buildbot/bin/python'}
03:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:02 INFO - Collecting jsonschema==2.5.1
03:14:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:14:03 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:14:04 INFO - Installing collected packages: functools32, jsonschema
03:14:04 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:14:04 INFO - Return code: 0
03:14:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:14: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')]}
03:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14: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')]}
03:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:04 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:04 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
03:14:04 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
03:14:04 INFO - Using env: {'DISPLAY': ':0',
03:14:04 INFO - 'HOME': '/home/cltbld',
03:14:04 INFO - 'LANG': 'en_US.UTF-8',
03:14:04 INFO - 'LANGUAGE': 'en_US:en',
03:14:04 INFO - 'LOGNAME': 'cltbld',
03:14:04 INFO - 'MAIL': '/var/mail/cltbld',
03:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:04 INFO - 'MOZ_NO_REMOTE': '1',
03:14:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:04 INFO - 'NO_EM_RESTART': '1',
03:14:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:04 INFO - 'PWD': '/builds/slave/test',
03:14:04 INFO - 'SHELL': '/bin/bash',
03:14:04 INFO - 'SHLVL': '1',
03:14:04 INFO - 'TERM': 'linux',
03:14:04 INFO - 'TMOUT': '86400',
03:14:04 INFO - 'USER': 'cltbld',
03:14:04 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:04 INFO - '_': '/tools/buildbot/bin/python'}
03:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:14:05 INFO - Return code: 0
03:14:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
03:14:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
03:14:05 INFO - Using env: {'DISPLAY': ':0',
03:14:05 INFO - 'HOME': '/home/cltbld',
03:14:05 INFO - 'LANG': 'en_US.UTF-8',
03:14:05 INFO - 'LANGUAGE': 'en_US:en',
03:14:05 INFO - 'LOGNAME': 'cltbld',
03:14:05 INFO - 'MAIL': '/var/mail/cltbld',
03:14:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:05 INFO - 'MOZ_NO_REMOTE': '1',
03:14:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:05 INFO - 'NO_EM_RESTART': '1',
03:14:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:05 INFO - 'PWD': '/builds/slave/test',
03:14:05 INFO - 'SHELL': '/bin/bash',
03:14:05 INFO - 'SHLVL': '1',
03:14:05 INFO - 'TERM': 'linux',
03:14:05 INFO - 'TMOUT': '86400',
03:14:05 INFO - 'USER': 'cltbld',
03:14:05 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:05 INFO - '_': '/tools/buildbot/bin/python'}
03:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:05 INFO - Collecting blobuploader==1.2.4
03:14:06 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:14:07 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:14:08 INFO - Installing collected packages: requests, docopt, blobuploader
03:14:08 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:14:08 INFO - Return code: 0
03:14:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:08 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:08 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
03:14:08 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
03:14:08 INFO - Using env: {'DISPLAY': ':0',
03:14:08 INFO - 'HOME': '/home/cltbld',
03:14:08 INFO - 'LANG': 'en_US.UTF-8',
03:14:08 INFO - 'LANGUAGE': 'en_US:en',
03:14:08 INFO - 'LOGNAME': 'cltbld',
03:14:08 INFO - 'MAIL': '/var/mail/cltbld',
03:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:08 INFO - 'MOZ_NO_REMOTE': '1',
03:14:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:08 INFO - 'NO_EM_RESTART': '1',
03:14:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:08 INFO - 'PWD': '/builds/slave/test',
03:14:08 INFO - 'SHELL': '/bin/bash',
03:14:08 INFO - 'SHLVL': '1',
03:14:08 INFO - 'TERM': 'linux',
03:14:08 INFO - 'TMOUT': '86400',
03:14:08 INFO - 'USER': 'cltbld',
03:14:08 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:08 INFO - '_': '/tools/buildbot/bin/python'}
03:14:09 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:09 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:14:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:14:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:14:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:14:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:14:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:14:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:14:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:14:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:14:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:14:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:14:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:14:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:14:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:14:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:14:12 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:14:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:14:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:14:12 INFO - Running setup.py install for manifestparser: started
03:14:12 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:14:12 INFO - Running setup.py install for mozcrash: started
03:14:13 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:14:13 INFO - Running setup.py install for mozdebug: started
03:14:13 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:14:13 INFO - Running setup.py install for mozdevice: started
03:14:13 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:14:13 INFO - Running setup.py install for mozfile: started
03:14:13 INFO - Running setup.py install for mozfile: finished with status 'done'
03:14:13 INFO - Running setup.py install for mozhttpd: started
03:14:13 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:14:13 INFO - Running setup.py install for mozinfo: started
03:14:14 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:14:14 INFO - Running setup.py install for mozInstall: started
03:14:14 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:14:14 INFO - Running setup.py install for mozleak: started
03:14:14 INFO - Running setup.py install for mozleak: finished with status 'done'
03:14:14 INFO - Running setup.py install for mozlog: started
03:14:14 INFO - Running setup.py install for mozlog: finished with status 'done'
03:14:14 INFO - Running setup.py install for moznetwork: started
03:14:15 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:14:15 INFO - Running setup.py install for mozprocess: started
03:14:15 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:14:15 INFO - Running setup.py install for mozprofile: started
03:14:15 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:14:15 INFO - Running setup.py install for mozrunner: started
03:14:15 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:14:15 INFO - Running setup.py install for mozscreenshot: started
03:14:15 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:14:15 INFO - Running setup.py install for moztest: started
03:14:16 INFO - Running setup.py install for moztest: finished with status 'done'
03:14:16 INFO - Running setup.py install for mozversion: started
03:14:16 INFO - Running setup.py install for mozversion: finished with status 'done'
03:14:16 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
03:14:16 INFO - Return code: 0
03:14:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:14: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')]}
03:14:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14: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')]}
03:14:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:16 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:16 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
03:14:16 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
03:14:16 INFO - Using env: {'DISPLAY': ':0',
03:14:16 INFO - 'HOME': '/home/cltbld',
03:14:16 INFO - 'LANG': 'en_US.UTF-8',
03:14:16 INFO - 'LANGUAGE': 'en_US:en',
03:14:16 INFO - 'LOGNAME': 'cltbld',
03:14:16 INFO - 'MAIL': '/var/mail/cltbld',
03:14:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:16 INFO - 'MOZ_NO_REMOTE': '1',
03:14:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:16 INFO - 'NO_EM_RESTART': '1',
03:14:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:16 INFO - 'PWD': '/builds/slave/test',
03:14:16 INFO - 'SHELL': '/bin/bash',
03:14:16 INFO - 'SHLVL': '1',
03:14:16 INFO - 'TERM': 'linux',
03:14:16 INFO - 'TMOUT': '86400',
03:14:16 INFO - 'USER': 'cltbld',
03:14:16 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:16 INFO - '_': '/tools/buildbot/bin/python'}
03:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:17 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:14:17 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))
03:14:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:14:17 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))
03:14:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:14:17 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))
03:14:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:14:17 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))
03:14:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:14:18 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))
03:14:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:14:18 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))
03:14:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:14:18 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))
03:14:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:14:18 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))
03:14:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:14:18 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))
03:14:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:14:19 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))
03:14:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:14:19 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))
03:14:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:14:19 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))
03:14:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:14:19 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))
03:14:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:14:19 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))
03:14:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:14:20 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))
03:14:20 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:14:20 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))
03:14:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:14:20 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))
03:14:20 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:14:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
03:14:21 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))
03:14:21 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))
03:14:21 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))
03:14:21 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))
03:14:21 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))
03:14:21 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))
03:14:21 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:14:22 INFO - Installing collected packages: six, blessings
03:14:22 INFO - Successfully installed blessings-1.6 six-1.10.0
03:14:22 INFO - Return code: 0
03:14:22 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:14:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:14:23 INFO - Reading from file tmpfile_stdout
03:14:23 INFO - Reading from file tmpfile_stderr
03:14:23 INFO - Current package versions:
03:14:23 INFO - blessings == 1.6
03:14:23 INFO - blobuploader == 1.2.4
03:14:23 INFO - docopt == 0.6.1
03:14:23 INFO - functools32 == 3.2.3.post2
03:14:23 INFO - jsonschema == 2.5.1
03:14:23 INFO - manifestparser == 1.2
03:14:23 INFO - mozInstall == 1.14
03:14:23 INFO - mozcrash == 1.0
03:14:23 INFO - mozdebug == 0.1
03:14:23 INFO - mozdevice == 0.51
03:14:23 INFO - mozfile == 1.2
03:14:23 INFO - mozhttpd == 0.7
03:14:23 INFO - mozinfo == 0.10
03:14:23 INFO - mozleak == 0.1
03:14:23 INFO - mozlog == 3.5
03:14:23 INFO - moznetwork == 0.27
03:14:23 INFO - mozprocess == 0.25
03:14:23 INFO - mozprofile == 0.29
03:14:23 INFO - mozrunner == 6.13
03:14:23 INFO - mozscreenshot == 0.1
03:14:23 INFO - mozsystemmonitor == 0.3
03:14:23 INFO - moztest == 0.8
03:14:23 INFO - mozversion == 1.4
03:14:23 INFO - psutil == 3.1.1
03:14:23 INFO - requests == 1.2.3
03:14:23 INFO - six == 1.10.0
03:14:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:14: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')]}
03:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14: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')]}
03:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:23 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:23 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
03:14:23 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
03:14:23 INFO - Using env: {'DISPLAY': ':0',
03:14:23 INFO - 'HOME': '/home/cltbld',
03:14:23 INFO - 'LANG': 'en_US.UTF-8',
03:14:23 INFO - 'LANGUAGE': 'en_US:en',
03:14:23 INFO - 'LOGNAME': 'cltbld',
03:14:23 INFO - 'MAIL': '/var/mail/cltbld',
03:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:23 INFO - 'MOZ_NO_REMOTE': '1',
03:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:23 INFO - 'NO_EM_RESTART': '1',
03:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:23 INFO - 'PWD': '/builds/slave/test',
03:14:23 INFO - 'SHELL': '/bin/bash',
03:14:23 INFO - 'SHLVL': '1',
03:14:23 INFO - 'TERM': 'linux',
03:14:23 INFO - 'TMOUT': '86400',
03:14:23 INFO - 'USER': 'cltbld',
03:14:23 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:23 INFO - '_': '/tools/buildbot/bin/python'}
03:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:23 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))
03:14:23 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))
03:14:23 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))
03:14:23 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))
03:14:23 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))
03:14:23 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))
03:14:23 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))
03:14:23 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))
03:14:23 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))
03:14:23 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:14:24 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:14:25 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))
03:14:25 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))
03:14:25 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))
03:14:25 INFO - Installing collected packages: simplejson, requests
03:14:26 INFO - Found existing installation: requests 1.2.3
03:14:26 INFO - Uninstalling requests-1.2.3:
03:14:26 INFO - Successfully uninstalled requests-1.2.3
03:14:26 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
03:14:26 INFO - Return code: 0
03:14:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:14:26 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')]}
03:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14:26 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')]}
03:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:26 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 0x11641f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x132ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14d1cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326', '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
03:14:26 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
03:14:26 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
03:14:26 INFO - Using env: {'DISPLAY': ':0',
03:14:26 INFO - 'HOME': '/home/cltbld',
03:14:26 INFO - 'LANG': 'en_US.UTF-8',
03:14:26 INFO - 'LANGUAGE': 'en_US:en',
03:14:26 INFO - 'LOGNAME': 'cltbld',
03:14:26 INFO - 'MAIL': '/var/mail/cltbld',
03:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:26 INFO - 'MOZ_NO_REMOTE': '1',
03:14:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:26 INFO - 'NO_EM_RESTART': '1',
03:14:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:26 INFO - 'PWD': '/builds/slave/test',
03:14:26 INFO - 'SHELL': '/bin/bash',
03:14:26 INFO - 'SHLVL': '1',
03:14:26 INFO - 'TERM': 'linux',
03:14:26 INFO - 'TMOUT': '86400',
03:14:26 INFO - 'USER': 'cltbld',
03:14:26 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:26 INFO - '_': '/tools/buildbot/bin/python'}
03:14:26 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:14:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:14:26 INFO - Return code: 0
03:14:26 INFO - Running post-action listener: _resource_record_post_action
03:14:26 INFO - Running post-action listener: _start_resource_monitoring
03:14:26 INFO - Starting resource monitoring.
03:14:26 INFO - [mozharness: 2017-10-28 10:14:26.932865Z] Finished create-virtualenv step (success)
03:14:26 INFO - [mozharness: 2017-10-28 10:14:26.933369Z] Running install step.
03:14:26 INFO - Running pre-action listener: _resource_record_pre_action
03:14:26 INFO - Running main action method: install
03:14:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:14:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:14:27 INFO - Reading from file tmpfile_stdout
03:14:27 INFO - Reading from file tmpfile_stderr
03:14:27 INFO - Detecting whether we're running mozinstall >=1.0...
03:14:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:14:27 INFO - Reading from file tmpfile_stdout
03:14:27 INFO - Output received:
03:14:27 INFO - Usage: mozinstall [options] installer
03:14:27 INFO - Options:
03:14:27 INFO - -h, --help show this help message and exit
03:14:27 INFO - -d DEST, --destination=DEST
03:14:27 INFO - Directory to install application into. [default:
03:14:27 INFO - "/builds/slave/test"]
03:14:27 INFO - --app=APP Application being installed. [default: firefox]
03:14:27 INFO - mkdir: /builds/slave/test/build/application
03:14:27 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']
03:14:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:14:45 INFO - Reading from file tmpfile_stdout
03:14:45 INFO - Output received:
03:14:45 INFO - /builds/slave/test/build/application/firefox/firefox
03:14:45 INFO - Running post-action listener: _resource_record_post_action
03:14:45 INFO - [mozharness: 2017-10-28 10:14:45.670469Z] Finished install step (success)
03:14:45 INFO - [mozharness: 2017-10-28 10:14:45.670743Z] Running setup-mitmproxy step.
03:14:45 INFO - Running pre-action listener: _resource_record_pre_action
03:14:45 INFO - Running main action method: setup_mitmproxy
03:14:45 INFO - Skipping: mitmproxy is not required
03:14:45 INFO - Running post-action listener: _resource_record_post_action
03:14:45 INFO - [mozharness: 2017-10-28 10:14:45.671873Z] Finished setup-mitmproxy step (success)
03:14:45 INFO - [mozharness: 2017-10-28 10:14:45.672094Z] Running run-tests step.
03:14:45 INFO - Running pre-action listener: _resource_record_pre_action
03:14:45 INFO - Running pre-action listener: _set_gcov_prefix
03:14:45 INFO - Running main action method: run_tests
03:14:45 WARNING - Try message not found.
03:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:14:45 INFO - Python 2.7.3
03:14:45 INFO - Return code: 0
03:14:45 INFO - grabbing minidump binary from tooltool
03:14:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:45 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 0x14cee60>, '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 0x14cfd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x14d01f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:14:45 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
03:14:45 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
03:14:45 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
03:14:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:14:45 INFO - Return code: 0
03:14:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:14:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:14:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:14:45 INFO - ENV: RUST_BACKTRACE is now full
03:14:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:14:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
03:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
03:14:45 INFO - Using env: {'DISPLAY': ':0',
03:14:45 INFO - 'HOME': '/home/cltbld',
03:14:45 INFO - 'LANG': 'en_US.UTF-8',
03:14:45 INFO - 'LANGUAGE': 'en_US:en',
03:14:45 INFO - 'LOGNAME': 'cltbld',
03:14:45 INFO - 'MAIL': '/var/mail/cltbld',
03:14:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:14:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:14:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:45 INFO - 'MOZ_NO_REMOTE': '1',
03:14:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:14:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:45 INFO - 'NO_EM_RESTART': '1',
03:14:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:45 INFO - 'PWD': '/builds/slave/test',
03:14:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:14:45 INFO - 'RUST_BACKTRACE': 'full',
03:14:45 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:14:45 INFO - 'SHELL': '/bin/bash',
03:14:45 INFO - 'SHLVL': '1',
03:14:45 INFO - 'TERM': 'linux',
03:14:45 INFO - 'TMOUT': '86400',
03:14:45 INFO - 'USER': 'cltbld',
03:14:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:45 INFO - '_': '/tools/buildbot/bin/python'}
03:14:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
03:14:46 INFO - mozversion application_buildid: 20171028083225
03:14:46 INFO - mozversion application_changeset: c16bc8097c102cdd2b70d8e7db3656222455a6ed
03:14:46 INFO - mozversion application_display_name: Nightly
03:14:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:14:46 INFO - mozversion application_name: Firefox
03:14:46 INFO - mozversion application_remotingname: firefox
03:14:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:14:46 INFO - mozversion application_vendor: Mozilla
03:14:46 INFO - mozversion application_version: 58.0a1
03:14:46 INFO - mozversion platform_buildid: 20171028083225
03:14:46 INFO - mozversion platform_changeset: c16bc8097c102cdd2b70d8e7db3656222455a6ed
03:14:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:14:46 INFO - mozversion platform_version: 58.0a1
03:14:46 INFO - using testdate: 1509185686
03:14:46 INFO - actual date: 1509185686
03:14:46 INFO - starting webserver on 'localhost:34051'
03:14:46 INFO - SUITE-START | Running 2 tests
03:14:46 INFO - TEST-START | basic_compositor_video
03:14:46 INFO - Initialising browser for basic_compositor_video test...
03:14:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34051/getInfo.html -profile /tmp/tmprPVREb/profile
03:14:46 INFO - TEST-INFO | started process 31669 (/builds/slave/test/build/application/firefox/firefox http://localhost:34051/getInfo.html)
03:14:51 INFO - TEST-INFO | 31669: exit 0
03:14:51 INFO - Browser initialized.
03:14:51 INFO - Running cycle 1/1 for basic_compositor_video test...
03:14:51 INFO - Using env: {'DISPLAY': ':0',
03:14:51 INFO - 'HOME': '/home/cltbld',
03:14:51 INFO - 'JSGC_DISABLE_POISONING': '1',
03:14:51 INFO - 'LANG': 'en_US.UTF-8',
03:14:51 INFO - 'LANGUAGE': 'en_US:en',
03:14:51 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:14:51 INFO - 'LOGNAME': 'cltbld',
03:14:51 INFO - 'MAIL': '/var/mail/cltbld',
03:14:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:14:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:14:51 INFO - 'MOZ_CRASHREPORTER': '1',
03:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:51 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:14:51 INFO - 'MOZ_NO_REMOTE': '1',
03:14:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:14:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:51 INFO - 'NO_EM_RESTART': '1',
03:14:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:51 INFO - 'PWD': '/builds/slave/test',
03:14:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:14:51 INFO - 'RUST_BACKTRACE': 'full',
03:14:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:14:51 INFO - 'SHELL': '/bin/bash',
03:14:51 INFO - 'SHLVL': '1',
03:14:51 INFO - 'TERM': 'linux',
03:14:51 INFO - 'TMOUT': '86400',
03:14:51 INFO - 'USER': 'cltbld',
03:14:51 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:51 INFO - '_': '/tools/buildbot/bin/python'}
03:14:51 INFO - TEST-INFO | started process 31949 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprPVREb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
03:14:52 INFO - PID 31949 |
03:14:52 INFO - PID 31949 | (/builds/slave/test/build/application/firefox/firefox:32003): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:14:52 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6808319327731092 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6690241868223519 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7714433293978753 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7042930153321978 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.127840440165063 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.160270270270271 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.4328718535469127 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.346304347826087 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682064393939396 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.349037433155073 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.274794520547951 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758560975609743 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.070996309963093 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.75631720430109 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346049382716036 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.834644970414203 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.365852272727269 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87057065217394 ms/frame
03:15:38 INFO - PID 31949 |
03:15:38 INFO - PID 31949 | Cycle 1(1): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:16:25 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6818133408071752 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6711946532999162 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7516316403969645 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7717847652790082 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.138413793103449 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.993333333333333 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.59362874251497 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.515237258347976 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.725648854961828 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634788732394372 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.17257627118644 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.106338383838386 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.157118959107791 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527000000000024 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39931818181819 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835266272189326 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583513513513504 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.239522471910124 ms/frame
03:16:25 INFO - PID 31949 |
03:16:25 INFO - PID 31949 | Cycle 1(2): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:17:11 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810112044817926 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673999999999998 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7805281899109795 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7264150129421916 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.150013831258645 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1499377593361 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.567395957193815 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.461444636678201 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683551136363631 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.435244565217396 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.566232394366189 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.054698492462322 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.030514705882364 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639574468085096 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.40097107438015 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.976676646706606 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.450610687022891 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050939226519343 ms/frame
03:17:11 INFO - PID 31949 |
03:17:11 INFO - PID 31949 | Cycle 1(3): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:17:57 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789367655288194 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6697537562604339 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7851279000594884 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7706548672566365 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116268861454047 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.058184584178498 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.4930791618160635 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4196153846153883 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769673076923076 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.602913165266111 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.1020202020202 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.259307692307686 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.870036231884049 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.4726439790576 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607310924369752 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.981137724550933 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.719433593750011 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.241516853932573 ms/frame
03:17:57 INFO - PID 31949 |
03:17:57 INFO - PID 31949 | Cycle 1(4): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:18:43 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6845199326221225 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6712447786131992 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7704483775811208 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7265918895599655 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.016780455153949 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1251134020618565 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.5179308323563916 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.450103448275858 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650574387947266 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.604915966386559 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.101329966329958 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.257641025641034 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.072804428044293 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.64156914893616 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.003799999999988 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054939759036161 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584845559845554 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932486338797796 ms/frame
03:18:43 INFO - PID 31949 |
03:18:43 INFO - PID 31949 | Cycle 1(5): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:19:29 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6798516237402017 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710233918128652 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7673616018845704 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7203138435081688 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093669849931787 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.116882716049384 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.386681715575623 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.362319327731089 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.803326885880077 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.619438202247192 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 9.970132890365456 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.524619047619039 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.992728937728936 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.58698412698414 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.448526970954362 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.910327380952367 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.905674603174587 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052127071823232 ms/frame
03:19:29 INFO - PID 31949 |
03:19:29 INFO - PID 31949 | Cycle 1(6): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:19:52 INFO - PID 31949 |
03:19:52 INFO - PID 31949 | (/builds/slave/test/build/application/firefox/firefox:32089): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:19:52 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6773253214086081 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.670137729549249 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7371221771858716 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7216437177280552 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.133305785123965 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.159989604989605 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.4405619266055045 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.356199664429527 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.61916666666667 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.305305039787794 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 9.804542483660127 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.005025000000005 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.407813688212931 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.874809782608677 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.553493723849357 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.700116959064335 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.631550387596908 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.051381215469629 ms/frame
03:20:15 INFO - PID 31949 |
03:20:15 INFO - PID 31949 | Cycle 1(7): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:21:02 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6854859550561798 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685195996663886 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7482925407925407 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.752062171628722 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1156447187928675 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8317528735632207 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.521214788732395 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4559930915371337 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715961904761906 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634507042253521 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.492115384615381 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.052914572864315 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.197070895522396 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640638297872353 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.658945147679317 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.274141104294465 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.858913043478262 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05162983425415 ms/frame
03:21:02 INFO - PID 31949 |
03:21:02 INFO - PID 31949 | Cycle 1(8): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:21:48 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682812675266405 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6715162907268168 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.767405771495877 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7504986876640425 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.208632538569426 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1764509394572 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.457252304147466 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.363159663865543 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683882575757576 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.57310584958217 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.639609929078011 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.85751231527094 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239981273408242 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.757715053763429 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606554621848742 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052801204819273 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584073359073365 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.753172043010734 ms/frame
03:21:48 INFO - PID 31949 |
03:21:48 INFO - PID 31949 | Cycle 1(9): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:22:34 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827285473920357 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669678631051753 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.765185294117647 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7485096153846162 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.214705056179774 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.203403361344541 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.598363309352518 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.510026315789474 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672514177693759 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603473389355739 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.001750000000005 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.951094527363175 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.992435897435902 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.758360215053765 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606365546218466 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.126636363636388 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.36397727272726 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111666666666698 ms/frame
03:22:34 INFO - PID 31949 |
03:22:34 INFO - PID 31949 | Cycle 1(10): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:23:20 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6798992161254198 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684778982485406 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.773667257683215 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7303027681660903 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 4.088208446866486 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.000720000000001 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.4370733104238287 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.34503344481605 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.607803738317756 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620154494382026 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 9.868782894736844 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 10.256256410256398 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.237715355805248 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.699625668449206 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659219409282702 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050240963855444 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540519230769217 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990192307692329 ms/frame
03:23:20 INFO - PID 31949 |
03:23:20 INFO - PID 31949 | Cycle 1(11): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:24:06 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6876940382452195 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687656380316933 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7618085731062834 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7431097560975604 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_startup = 3.953260869565217 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.167010416666665 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_startup = 3.4334439359267734 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1_inclip = 3.397232597623092 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6614528301886855 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.494821428571423 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_startup = 10.067466442953018 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.480p.60fps.webm_scale_2_inclip = 9.854482758620685 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.9493613138686 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.64260638297875 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.769234042553208 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.123151515151513 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.407072243345999 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.815972972972983 ms/frame
03:24:06 INFO - PID 31949 |
03:24:06 INFO - PID 31949 | Cycle 1(12): loaded http://localhost:34051/tests/video/video_playback.html (next: http://localhost:34051/tests/video/video_playback.html)
03:24:07 INFO - PID 31949 | __start_tp_report
03:24:07 INFO - PID 31949 | _x_x_mozilla_page_load
03:24:07 INFO - PID 31949 | _x_x_mozilla_page_load_details
03:24:07 INFO - PID 31949 | |i|pagename|runs|
03:24:07 INFO - PID 31949 | |0;240p.120fps.mp4_scale_1_startup;1.6808319327731092;1.6818133408071752;1.6810112044817926;1.6789367655288194;1.6845199326221225;1.6798516237402017;1.6773253214086081;1.6854859550561798;1.682812675266405;1.6827285473920357;1.6798992161254198;1.6876940382452195
03:24:07 INFO - PID 31949 | |1;240p.120fps.mp4_scale_1_inclip;1.6690241868223519;1.6711946532999162;1.6673999999999998;1.6697537562604339;1.6712447786131992;1.6710233918128652;1.670137729549249;1.6685195996663886;1.6715162907268168;1.669678631051753;1.6684778982485406;1.6687656380316933
03:24:07 INFO - PID 31949 | |2;240p.120fps.mp4_scale_1.1_startup;1.7714433293978753;1.7516316403969645;1.7805281899109795;1.7851279000594884;1.7704483775811208;1.7673616018845704;1.7371221771858716;1.7482925407925407;1.767405771495877;1.765185294117647;1.773667257683215;1.7618085731062834
03:24:07 INFO - PID 31949 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7042930153321978;1.7717847652790082;1.7264150129421916;1.7706548672566365;1.7265918895599655;1.7203138435081688;1.7216437177280552;1.752062171628722;1.7504986876640425;1.7485096153846162;1.7303027681660903;1.7431097560975604
03:24:07 INFO - PID 31949 | |4;240p.120fps.mp4_scale_2_startup;4.127840440165063;4.138413793103449;4.150013831258645;4.116268861454047;4.016780455153949;4.093669849931787;4.133305785123965;4.1156447187928675;4.208632538569426;4.214705056179774;4.088208446866486;3.953260869565217
03:24:07 INFO - PID 31949 | |5;240p.120fps.mp4_scale_2_inclip;4.160270270270271;3.993333333333333;4.1499377593361;4.058184584178498;4.1251134020618565;4.116882716049384;4.159989604989605;3.8317528735632207;4.1764509394572;4.203403361344541;4.000720000000001;4.167010416666665
03:24:07 INFO - PID 31949 | |6;480p.60fps.webm_scale_1_startup;3.4328718535469127;3.59362874251497;3.567395957193815;3.4930791618160635;3.5179308323563916;3.386681715575623;3.4405619266055045;3.521214788732395;3.457252304147466;3.598363309352518;3.4370733104238287;3.4334439359267734
03:24:07 INFO - PID 31949 | |7;480p.60fps.webm_scale_1_inclip;3.346304347826087;3.515237258347976;3.461444636678201;3.4196153846153883;3.450103448275858;3.362319327731089;3.356199664429527;3.4559930915371337;3.363159663865543;3.510026315789474;3.34503344481605;3.397232597623092
03:24:07 INFO - PID 31949 | |8;480p.60fps.webm_scale_1.1_startup;5.682064393939396;5.725648854961828;5.683551136363631;5.769673076923076;5.650574387947266;5.803326885880077;5.61916666666667;5.715961904761906;5.683882575757576;5.672514177693759;5.607803738317756;5.6614528301886855
03:24:07 INFO - PID 31949 | |9;480p.60fps.webm_scale_1.1_inclip;5.349037433155073;5.634788732394372;5.435244565217396;5.602913165266111;5.604915966386559;5.619438202247192;5.305305039787794;5.634507042253521;5.57310584958217;5.603473389355739;5.620154494382026;5.494821428571423
03:24:07 INFO - PID 31949 | |10;480p.60fps.webm_scale_2_startup;10.274794520547951;10.17257627118644;10.566232394366189;10.1020202020202;10.101329966329958;9.970132890365456;9.804542483660127;10.492115384615381;10.639609929078011;10.001750000000005;9.868782894736844;10.067466442953018
03:24:07 INFO - PID 31949 | |11;480p.60fps.webm_scale_2_inclip;9.758560975609743;10.106338383838386;10.054698492462322;10.259307692307686;10.257641025641034;9.524619047619039;10.005025000000005;10.052914572864315;9.85751231527094;9.951094527363175;10.256256410256398;9.854482758620685
03:24:07 INFO - PID 31949 | |12;1080p.60fps.mp4_scale_1_startup;11.070996309963093;11.157118959107791;11.030514705882364;10.870036231884049;11.072804428044293;10.992728937728936;11.407813688212931;11.197070895522396;11.239981273408242;10.992435897435902;11.237715355805248;10.9493613138686
03:24:07 INFO - PID 31949 | |13;1080p.60fps.mp4_scale_1_inclip;10.75631720430109;10.527000000000024;10.639574468085096;10.4726439790576;10.64156914893616;10.58698412698414;10.874809782608677;10.640638297872353;10.757715053763429;10.758360215053765;10.699625668449206;10.64260638297875
03:24:07 INFO - PID 31949 | |14;1080p.60fps.mp4_scale_1.1_startup;12.346049382716036;12.39931818181819;12.40097107438015;12.607310924369752;12.003799999999988;12.448526970954362;12.553493723849357;12.658945147679317;12.606554621848742;12.606365546218466;12.659219409282702;12.769234042553208
03:24:07 INFO - PID 31949 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.834644970414203;11.835266272189326;11.976676646706606;11.981137724550933;12.054939759036161;11.910327380952367;11.700116959064335;12.274141104294465;12.052801204819273;12.126636363636388;12.050240963855444;12.123151515151513
03:24:07 INFO - PID 31949 | |16;1080p.60fps.mp4_scale_2_startup;11.365852272727269;11.583513513513504;11.450610687022891;11.719433593750011;11.584845559845554;11.905674603174587;11.631550387596908;11.858913043478262;11.584073359073365;11.36397727272726;11.540519230769217;11.407072243345999
03:24:07 INFO - PID 31949 | |17;1080p.60fps.mp4_scale_2_inclip;10.87057065217394;11.239522471910124;11.050939226519343;11.241516853932573;10.932486338797796;11.052127071823232;11.051381215469629;11.05162983425415;10.753172043010734;11.111666666666698;10.990192307692329;10.815972972972983
03:24:07 INFO - PID 31949 | __end_tp_report
03:24:07 INFO - PID 31949 | __start_cc_report
03:24:07 INFO - PID 31949 | _x_x_mozilla_cycle_collect,558
03:24:07 INFO - PID 31949 | __end_cc_report
03:24:07 INFO - PID 31949 | __startTimestamp1509186247118__endTimestamp
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | ------- Summary: start -------
03:24:07 INFO - PID 31949 | Number of tests: 18
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
03:24:07 INFO - PID 31949 | 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
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#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
03:24:07 INFO - PID 31949 | 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
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.77 Median:1.77 stddev:0.01 (0.8%) stddev-sans-first:0.01
03:24:07 INFO - PID 31949 | Values: 1.8 1.8 1.8 1.8 1.8 1.8 1.7 1.7 1.8 1.8 1.8 1.8
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.74 Median:1.75 stddev:0.02 (1.2%) stddev-sans-first:0.02
03:24:07 INFO - PID 31949 | Values: 1.7 1.8 1.7 1.8 1.7 1.7 1.7 1.8 1.8 1.7 1.7 1.7
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.11 Median:4.13 stddev:0.07 (1.8%) stddev-sans-first:0.08
03:24:07 INFO - PID 31949 | Values: 4.1 4.1 4.2 4.1 4.0 4.1 4.1 4.1 4.2 4.2 4.1 4.0
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.10 Median:4.15 stddev:0.11 (2.6%) stddev-sans-first:0.11
03:24:07 INFO - PID 31949 | Values: 4.2 4.0 4.1 4.1 4.1 4.1 4.2 3.8 4.2 4.2 4.0 4.2
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.49 Median:3.51 stddev:0.07 (2.0%) stddev-sans-first:0.07
03:24:07 INFO - PID 31949 | Values: 3.4 3.6 3.6 3.5 3.5 3.4 3.4 3.5 3.5 3.6 3.4 3.4
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.43 stddev:0.06 (1.8%) stddev-sans-first:0.06
03:24:07 INFO - PID 31949 | Values: 3.3 3.5 3.5 3.4 3.5 3.4 3.4 3.5 3.4 3.5 3.3 3.4
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.69 Median:5.68 stddev:0.06 (1.0%) stddev-sans-first:0.06
03:24:07 INFO - PID 31949 | Values: 5.7 5.7 5.7 5.8 5.7 5.8 5.6 5.7 5.7 5.7 5.6 5.7
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.54 Median:5.60 stddev:0.12 (2.1%) stddev-sans-first:0.10
03:24:07 INFO - PID 31949 | Values: 5.3 5.6 5.4 5.6 5.6 5.6 5.3 5.6 5.6 5.6 5.6 5.5
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.17 Median:10.53 stddev:0.27 (2.6%) stddev-sans-first:0.28
03:24:07 INFO - PID 31949 | Values: 10.3 10.2 10.6 10.1 10.1 10.0 9.8 10.5 10.6 10.0 9.9 10.1
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.99 Median:9.89 stddev:0.22 (2.2%) stddev-sans-first:0.22
03:24:07 INFO - PID 31949 | Values: 9.8 10.1 10.1 10.3 10.3 9.5 10.0 10.1 9.9 10.0 10.3 9.9
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.10 Median:11.11 stddev:0.15 (1.4%) stddev-sans-first:0.16
03:24:07 INFO - PID 31949 | Values: 11.1 11.2 11.0 10.9 11.1 11.0 11.4 11.2 11.2 11.0 11.2 10.9
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.67 Median:10.67 stddev:0.11 (1.0%) stddev-sans-first:0.11
03:24:07 INFO - PID 31949 | Values: 10.8 10.5 10.6 10.5 10.6 10.6 10.9 10.6 10.8 10.8 10.7 10.6
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.50 Median:12.61 stddev:0.20 (1.6%) stddev-sans-first:0.21
03:24:07 INFO - PID 31949 | Values: 12.3 12.4 12.4 12.6 12.0 12.4 12.6 12.7 12.6 12.6 12.7 12.8
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.99 Median:12.05 stddev:0.16 (1.3%) stddev-sans-first:0.15
03:24:07 INFO - PID 31949 | Values: 11.8 11.8 12.0 12.0 12.1 11.9 11.7 12.3 12.1 12.1 12.1 12.1
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.58 Median:11.58 stddev:0.18 (1.5%) stddev-sans-first:0.17
03:24:07 INFO - PID 31949 | Values: 11.4 11.6 11.5 11.7 11.6 11.9 11.6 11.9 11.6 11.4 11.5 11.4
03:24:07 INFO - PID 31949 |
03:24:07 INFO - PID 31949 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.01 Median:11.05 stddev:0.15 (1.4%) stddev-sans-first:0.15
03:24:07 INFO - PID 31949 | Values: 10.9 11.2 11.1 11.2 10.9 11.1 11.1 11.1 10.8 11.1 11.0 10.8
03:24:07 INFO - PID 31949 | -------- Summary: end --------
03:24:07 INFO - PID 31949 |
03:24:07 INFO - TEST-INFO | 31949: exit 0
03:24:07 INFO - TEST-OK | basic_compositor_video | took 561575ms
03:24:07 INFO - TEST-START | glvideo
03:24:07 INFO - Initialising browser for glvideo test...
03:24:07 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:34051/getInfo.html -profile /tmp/tmp4N9YKz/profile
03:24:07 INFO - TEST-INFO | started process 768 (/builds/slave/test/build/application/firefox/firefox http://localhost:34051/getInfo.html)
03:24:13 INFO - TEST-INFO | 768: exit 0
03:24:13 INFO - Browser initialized.
03:24:13 INFO - Running cycle 1/1 for glvideo test...
03:24:13 INFO - Using env: {'DISPLAY': ':0',
03:24:13 INFO - 'HOME': '/home/cltbld',
03:24:13 INFO - 'JSGC_DISABLE_POISONING': '1',
03:24:13 INFO - 'LANG': 'en_US.UTF-8',
03:24:13 INFO - 'LANGUAGE': 'en_US:en',
03:24:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:24:13 INFO - 'LOGNAME': 'cltbld',
03:24:13 INFO - 'MAIL': '/var/mail/cltbld',
03:24:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:24:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:24:13 INFO - 'MOZ_CRASHREPORTER': '1',
03:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:24:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:24:13 INFO - 'MOZ_NO_REMOTE': '1',
03:24:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:24:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:24:13 INFO - 'NO_EM_RESTART': '1',
03:24:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:24:13 INFO - 'PWD': '/builds/slave/test',
03:24:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:24:13 INFO - 'RUST_BACKTRACE': 'full',
03:24:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:24:13 INFO - 'SHELL': '/bin/bash',
03:24:13 INFO - 'SHLVL': '1',
03:24:13 INFO - 'TERM': 'linux',
03:24:13 INFO - 'TMOUT': '86400',
03:24:13 INFO - 'USER': 'cltbld',
03:24:13 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326',
03:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:24:13 INFO - '_': '/tools/buildbot/bin/python'}
03:24:13 INFO - TEST-INFO | started process 1054 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4N9YKz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:24:13 INFO - PID 1054 |
03:24:13 INFO - PID 1054 | (/builds/slave/test/build/application/firefox/firefox:1111): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:24:13 INFO - PID 1054 |
03:24:15 INFO - PID 1054 | [talos glvideo result] Mean tick time across 100 ticks: 6.890600000000001 ms
03:24:15 INFO - PID 1054 | Cycle 1(1): loaded http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html)
03:24:16 INFO - PID 1054 | [talos glvideo result] Mean tick time across 100 ticks: 6.56865 ms
03:24:16 INFO - PID 1054 | Cycle 1(2): loaded http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html)
03:24:18 INFO - PID 1054 | [talos glvideo result] Mean tick time across 100 ticks: 6.4752 ms
03:24:18 INFO - PID 1054 | Cycle 1(3): loaded http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html)
03:24:19 INFO - PID 1054 | [talos glvideo result] Mean tick time across 100 ticks: 6.643350000000001 ms
03:24:19 INFO - PID 1054 | Cycle 1(4): loaded http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html)
03:24:20 INFO - PID 1054 | [talos glvideo result] Mean tick time across 100 ticks: 6.652099999999999 ms
03:24:20 INFO - PID 1054 | Cycle 1(5): loaded http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:34051/tests/webgl/benchmarks/video/video_upload.html)
03:24:20 INFO - PID 1054 | __start_tp_report
03:24:20 INFO - PID 1054 | _x_x_mozilla_page_load
03:24:20 INFO - PID 1054 | _x_x_mozilla_page_load_details
03:24:20 INFO - PID 1054 | |i|pagename|runs|
03:24:20 INFO - PID 1054 | |0;Mean tick time across 100 ticks: ;6.890600000000001;6.56865;6.4752;6.643350000000001;6.652099999999999
03:24:20 INFO - PID 1054 | __end_tp_report
03:24:20 INFO - PID 1054 | __start_cc_report
03:24:20 INFO - PID 1054 | _x_x_mozilla_cycle_collect,270
03:24:20 INFO - PID 1054 | __end_cc_report
03:24:20 INFO - PID 1054 | __startTimestamp1509186260885__endTimestamp
03:24:20 INFO - PID 1054 |
03:24:20 INFO - PID 1054 | ------- Summary: start -------
03:24:20 INFO - PID 1054 | Number of tests: 1
03:24:20 INFO - PID 1054 |
03:24:20 INFO - PID 1054 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.65 Median:6.64 stddev:0.15 (2.3%) stddev-sans-first:0.08
03:24:20 INFO - PID 1054 | Values: 6.9 6.6 6.5 6.6 6.7
03:24:20 INFO - PID 1054 | -------- Summary: end --------
03:24:20 INFO - PID 1054 |
03:24:21 INFO - PID 1054 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:24:21 INFO - TEST-INFO | 1054: exit 0
03:24:21 INFO - TEST-OK | glvideo | took 13821ms
03:24:21 INFO - SUITE-END | took 575s
03:24:21 INFO - Completed test suite (00:09:35)
03:24:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.60986294747674, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6808319327731092, 1.6818133408071752, 1.6810112044817926, 1.6789367655288194, 1.6845199326221225, 1.6798516237402017, 1.6773253214086081, 1.6854859550561798, 1.682812675266405, 1.6827285473920357, 1.6798992161254198, 1.6876940382452195], "value": 1.6818133408071752, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6690241868223519, 1.6711946532999162, 1.6673999999999998, 1.6697537562604339, 1.6712447786131992, 1.6710233918128652, 1.670137729549249, 1.6685195996663886, 1.6715162907268168, 1.669678631051753, 1.6684778982485406, 1.6687656380316933], "value": 1.6697537562604339, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7714433293978753, 1.7516316403969645, 1.7805281899109795, 1.7851279000594884, 1.7704483775811208, 1.7673616018845704, 1.7371221771858716, 1.7482925407925407, 1.767405771495877, 1.765185294117647, 1.773667257683215, 1.7618085731062834], "value": 1.7673616018845704, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7042930153321978, 1.7717847652790082, 1.7264150129421916, 1.7706548672566365, 1.7265918895599655, 1.7203138435081688, 1.7216437177280552, 1.752062171628722, 1.7504986876640425, 1.7485096153846162, 1.7303027681660903, 1.7431097560975604], "value": 1.7431097560975604, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.127840440165063, 4.138413793103449, 4.150013831258645, 4.116268861454047, 4.016780455153949, 4.093669849931787, 4.133305785123965, 4.1156447187928675, 4.208632538569426, 4.214705056179774, 4.088208446866486, 3.953260869565217], "value": 4.116268861454047, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.160270270270271, 3.993333333333333, 4.1499377593361, 4.058184584178498, 4.1251134020618565, 4.116882716049384, 4.159989604989605, 3.8317528735632207, 4.1764509394572, 4.203403361344541, 4.000720000000001, 4.167010416666665], "value": 4.1251134020618565, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4328718535469127, 3.59362874251497, 3.567395957193815, 3.4930791618160635, 3.5179308323563916, 3.386681715575623, 3.4405619266055045, 3.521214788732395, 3.457252304147466, 3.598363309352518, 3.4370733104238287, 3.4334439359267734], "value": 3.4930791618160635, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.346304347826087, 3.515237258347976, 3.461444636678201, 3.4196153846153883, 3.450103448275858, 3.362319327731089, 3.356199664429527, 3.4559930915371337, 3.363159663865543, 3.510026315789474, 3.34503344481605, 3.397232597623092], "value": 3.4196153846153883, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.682064393939396, 5.725648854961828, 5.683551136363631, 5.769673076923076, 5.650574387947266, 5.803326885880077, 5.61916666666667, 5.715961904761906, 5.683882575757576, 5.672514177693759, 5.607803738317756, 5.6614528301886855], "value": 5.683551136363631, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.349037433155073, 5.634788732394372, 5.435244565217396, 5.602913165266111, 5.604915966386559, 5.619438202247192, 5.305305039787794, 5.634507042253521, 5.57310584958217, 5.603473389355739, 5.620154494382026, 5.494821428571423], "value": 5.603473389355739, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.274794520547951, 10.17257627118644, 10.566232394366189, 10.1020202020202, 10.101329966329958, 9.970132890365456, 9.804542483660127, 10.492115384615381, 10.639609929078011, 10.001750000000005, 9.868782894736844, 10.067466442953018], "value": 10.101329966329958, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.758560975609743, 10.106338383838386, 10.054698492462322, 10.259307692307686, 10.257641025641034, 9.524619047619039, 10.005025000000005, 10.052914572864315, 9.85751231527094, 9.951094527363175, 10.256256410256398, 9.854482758620685], "value": 10.052914572864315, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.070996309963093, 11.157118959107791, 11.030514705882364, 10.870036231884049, 11.072804428044293, 10.992728937728936, 11.407813688212931, 11.197070895522396, 11.239981273408242, 10.992435897435902, 11.237715355805248, 10.9493613138686], "value": 11.072804428044293, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.75631720430109, 10.527000000000024, 10.639574468085096, 10.4726439790576, 10.64156914893616, 10.58698412698414, 10.874809782608677, 10.640638297872353, 10.757715053763429, 10.758360215053765, 10.699625668449206, 10.64260638297875], "value": 10.64156914893616, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.346049382716036, 12.39931818181819, 12.40097107438015, 12.607310924369752, 12.003799999999988, 12.448526970954362, 12.553493723849357, 12.658945147679317, 12.606554621848742, 12.606365546218466, 12.659219409282702, 12.769234042553208], "value": 12.606365546218466, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.834644970414203, 11.835266272189326, 11.976676646706606, 11.981137724550933, 12.054939759036161, 11.910327380952367, 11.700116959064335, 12.274141104294465, 12.052801204819273, 12.126636363636388, 12.050240963855444, 12.123151515151513], "value": 12.050240963855444, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.365852272727269, 11.583513513513504, 11.450610687022891, 11.719433593750011, 11.584845559845554, 11.905674603174587, 11.631550387596908, 11.858913043478262, 11.584073359073365, 11.36397727272726, 11.540519230769217, 11.407072243345999], "value": 11.584073359073365, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.87057065217394, 11.239522471910124, 11.050939226519343, 11.241516853932573, 10.932486338797796, 11.052127071823232, 11.051381215469629, 11.05162983425415, 10.753172043010734, 11.111666666666698, 10.990192307692329, 10.815972972972983], "value": 11.051381215469629, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.890600000000001, 6.56865, 6.4752, 6.643350000000001, 6.652099999999999], "value": 6.606, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}]}
03:24:21 INFO - Return code: 0
03:24:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:24:22 INFO - # TBPL SUCCESS #
03:24:22 INFO - Running post-action listener: _package_coverage_data
03:24:22 INFO - Running post-action listener: _resource_record_post_action
03:24:22 INFO - [mozharness: 2017-10-28 10:24:22.041954Z] Finished run-tests step (success)
03:24:22 INFO - Running post-run listener: _resource_record_post_run
03:24:22 INFO - Total resource usage - Wall time: 595s; CPU: 38.0%; Read bytes: 4096; Write bytes: 434188288; Read time: 160; Write time: 379456
03:24:22 INFO - TinderboxPrint: CPU usage
38.5%
03:24:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 160
03:24:22 INFO - TinderboxPrint: I/O write bytes / time
434,188,288 / 379,456
03:24:22 INFO - TinderboxPrint: CPU idle
2,869.1 (61.5%)
03:24:22 INFO - TinderboxPrint: CPU system
301.8 (6.5%)
03:24:22 INFO - TinderboxPrint: CPU user
1,479.7 (31.7%)
03:24:22 INFO - TinderboxPrint: Swap in / out
0 / 0
03:24:22 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 129220608; Read time: 0; Write time: 164044
03:24:22 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:24:22 INFO - run-tests - Wall time: 576s; CPU: 39.0%; Read bytes: 4096; Write bytes: 304967680; Read time: 160; Write time: 215412
03:24:22 INFO - Running post-run listener: _upload_blobber_files
03:24:22 INFO - Blob upload gear active.
03:24:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:24:22 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
03:24:22 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']
03:24:22 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
03:24:22 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
03:24:22 INFO - SNIMissingWarning
03:24:22 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
03:24:22 INFO - InsecurePlatformWarning
03:24:22 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.)
03:24:22 INFO - SubjectAltNameWarning
03:24:22 INFO - (blobuploader) - INFO - Open directory for files ...
03:24:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:24:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:24:22 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:24:22 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
03:24:22 INFO - InsecurePlatformWarning
03:24:23 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.)
03:24:23 INFO - SubjectAltNameWarning
03:24:23 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
03:24:23 INFO - InsecurePlatformWarning
03:24:23 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:24:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:24:23 INFO - (blobuploader) - INFO - Done attempting.
03:24:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:24:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:24:23 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:24:23 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
03:24:23 INFO - InsecurePlatformWarning
03:24:24 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.)
03:24:24 INFO - SubjectAltNameWarning
03:24:28 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
03:24:28 INFO - InsecurePlatformWarning
03:24:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:24:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:24:29 INFO - (blobuploader) - INFO - Done attempting.
03:24:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:24:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:24:29 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:24:29 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
03:24:29 INFO - InsecurePlatformWarning
03:24:29 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.)
03:24:29 INFO - SubjectAltNameWarning
03:24:29 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
03:24:29 INFO - InsecurePlatformWarning
03:24:30 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:24:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:24:30 INFO - (blobuploader) - INFO - Done attempting.
03:24:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:24:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:24:30 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:24:30 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
03:24:30 INFO - InsecurePlatformWarning
03:24:30 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.)
03:24:30 INFO - SubjectAltNameWarning
03:24:30 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
03:24:30 INFO - InsecurePlatformWarning
03:24:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:24:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:24:30 INFO - (blobuploader) - INFO - Done attempting.
03:24:30 INFO - (blobuploader) - INFO - Iteration through files over.
03:24:30 INFO - Return code: 0
03:24:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:24:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:24:30 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02758b1b2a47397de631a052d551f99511a68f0a354c27e47e48851d5085b7ce2f129d657f48b88b3a8db1ead8e28b5c3b5fb627f9fb570c047a0fe7797fa818", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357916f3bab56fa037f07e09d14798221d991cecf66a6432fb1174a85f1f7000d0cba79e5ed71eaa899114889d9dba40b01bd9c42effb8490c78aa658c8497ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8befefd99a90d6f4d08206e6a1137bcfa3f568bb536bf81d4272764e4d01f050bdf334883c21b614bea041c60642775c174ddf5c180f4c057e735d077e92915b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be202496010d7f4bf2139ecad7d17a6e5e50ef14b2968262bb66125ab099fcfe27632e0f5dea844f7dab5ef6b3e597b3afae065ef8be1c92488e134908e6aa0a"}
03:24:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:24:30 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:24:30 INFO - Contents:
03:24:31 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02758b1b2a47397de631a052d551f99511a68f0a354c27e47e48851d5085b7ce2f129d657f48b88b3a8db1ead8e28b5c3b5fb627f9fb570c047a0fe7797fa818", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357916f3bab56fa037f07e09d14798221d991cecf66a6432fb1174a85f1f7000d0cba79e5ed71eaa899114889d9dba40b01bd9c42effb8490c78aa658c8497ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8befefd99a90d6f4d08206e6a1137bcfa3f568bb536bf81d4272764e4d01f050bdf334883c21b614bea041c60642775c174ddf5c180f4c057e735d077e92915b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be202496010d7f4bf2139ecad7d17a6e5e50ef14b2968262bb66125ab099fcfe27632e0f5dea844f7dab5ef6b3e597b3afae065ef8be1c92488e134908e6aa0a"}
03:24:31 INFO - Running post-run listener: copy_logs_to_upload_dir
03:24:31 INFO - Copying logs to upload dir...
03:24:31 INFO - mkdir: /builds/slave/test/build/upload/logs
03:24:31 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=678.852063
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 18 secs) (at 2017-10-28 03:24:31.058370) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-28 03:24:31.061810) =========
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=8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02758b1b2a47397de631a052d551f99511a68f0a354c27e47e48851d5085b7ce2f129d657f48b88b3a8db1ead8e28b5c3b5fb627f9fb570c047a0fe7797fa818", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357916f3bab56fa037f07e09d14798221d991cecf66a6432fb1174a85f1f7000d0cba79e5ed71eaa899114889d9dba40b01bd9c42effb8490c78aa658c8497ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8befefd99a90d6f4d08206e6a1137bcfa3f568bb536bf81d4272764e4d01f050bdf334883c21b614bea041c60642775c174ddf5c180f4c057e735d077e92915b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be202496010d7f4bf2139ecad7d17a6e5e50ef14b2968262bb66125ab099fcfe27632e0f5dea844f7dab5ef6b3e597b3afae065ef8be1c92488e134908e6aa0a"}
build_url:https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011194
build_url: 'https://queue.taskcluster.net/v1/task/cHXMObpVQ5mn11leOXY0zQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02758b1b2a47397de631a052d551f99511a68f0a354c27e47e48851d5085b7ce2f129d657f48b88b3a8db1ead8e28b5c3b5fb627f9fb570c047a0fe7797fa818", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357916f3bab56fa037f07e09d14798221d991cecf66a6432fb1174a85f1f7000d0cba79e5ed71eaa899114889d9dba40b01bd9c42effb8490c78aa658c8497ed", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8befefd99a90d6f4d08206e6a1137bcfa3f568bb536bf81d4272764e4d01f050bdf334883c21b614bea041c60642775c174ddf5c180f4c057e735d077e92915b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/be202496010d7f4bf2139ecad7d17a6e5e50ef14b2968262bb66125ab099fcfe27632e0f5dea844f7dab5ef6b3e597b3afae065ef8be1c92488e134908e6aa0a"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-28 03:24:31.092105) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 03:24:31.092443) =========
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=8c6b6742ce8f6f39ef4ce27d000001f1-1509184382.737570-25706326
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.005061
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 03:24:31.176389) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-28 03:24:31.176706) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-28 03:24:31.177029) =========
========= Total master_lag: 0.22 =========