builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s
slave: talos-linux64-ix-079
starttime: 1509275285.63
results: success (0)
revision: d58c5cb053be123a9ede85cabce3b060c13cf66d
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.627875) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.628314) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.628591) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.647817) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.648158) =========
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=1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-29 04:08:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 46.7M=0s
2017-10-29 04:08:05 (46.7 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.137578
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.803944) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.804258) =========
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=1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.016245
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:05.853858) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-29 04:08:05.854186) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d58c5cb053be123a9ede85cabce3b060c13cf66d --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 d58c5cb053be123a9ede85cabce3b060c13cf66d --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=1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-29 04:08:05,911 truncating revision to first 12 chars
2017-10-29 04:08:05,911 Setting DEBUG logging.
2017-10-29 04:08:05,911 attempt 1/10
2017-10-29 04:08:05,911 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d58c5cb053be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-29 04:08:06,287 unpacking tar archive at: mozilla-central-d58c5cb053be/testing/mozharness/
program finished with exit code 0
elapsedTime=1.593635
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-29 04:08:07.465980) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:07.466323) =========
script_repo_revision: d58c5cb053be123a9ede85cabce3b060c13cf66d
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:07.466837) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:07.467106) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-29 04:08:07.481510) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 57 secs) (at 2017-10-29 04:08:07.481903) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
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=1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
04:08:07 INFO - MultiFileLogger online at 20171029 04:08:07 in /builds/slave/test
04:08:07 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
04:08:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
04:08:07 INFO - {'append_to_log': False,
04:08:07 INFO - 'base_work_dir': '/builds/slave/test',
04:08:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
04:08:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
04:08:07 INFO - 'branch': 'Firefox-Non-PGO',
04:08:07 INFO - 'buildbot_json_path': 'buildprops.json',
04:08:07 INFO - 'code_coverage': False,
04:08:07 INFO - 'config_files': ('talos/linux_config.py',),
04:08:07 INFO - 'default_actions': ('clobber',
04:08:07 INFO - 'read-buildbot-config',
04:08:07 INFO - 'download-and-extract',
04:08:07 INFO - 'populate-webroot',
04:08:07 INFO - 'create-virtualenv',
04:08:07 INFO - 'install',
04:08:07 INFO - 'setup-mitmproxy',
04:08:07 INFO - 'run-tests'),
04:08:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
04:08:07 INFO - 'disable_ccov_upload': False,
04:08:07 INFO - 'disable_stylo': False,
04:08:07 INFO - 'download_minidump_stackwalk': True,
04:08:07 INFO - 'download_symbols': 'ondemand',
04:08:07 INFO - 'enable_stylo': False,
04:08:07 INFO - 'enable_webrender': False,
04:08:07 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
04:08:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
04:08:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
04:08:07 INFO - 'gecko_profile': False,
04:08:07 INFO - 'gecko_profile_interval': 0,
04:08:07 INFO - 'installer_path': 'installer.exe',
04:08:07 INFO - 'log_level': 'info',
04:08:07 INFO - 'log_name': 'talos',
04:08:07 INFO - 'log_to_console': True,
04:08:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
04:08:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
04:08:07 INFO - 'opt_config_files': (),
04:08:07 INFO - 'pip_index': False,
04:08:07 INFO - 'suite': 'g4-stylo-disabled-e10s',
04:08:07 INFO - 'system_bits': '32',
04:08:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
04:08:07 INFO - 'title': 'talos-linux64-ix-079',
04:08:07 INFO - 'tooltool_cache': '/builds/tooltool_cache',
04:08:07 INFO - 'use_talos_json': True,
04:08:07 INFO - 'verify': 'False',
04:08:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
04:08:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
04:08:07 INFO - 'work_dir': 'build'}
04:08:07 INFO - [mozharness: 2017-10-29 11:08:07.671655Z] Running clobber step.
04:08:07 INFO - Running pre-action listener: _resource_record_pre_action
04:08:07 INFO - Running main action method: clobber
04:08:07 INFO - rmtree: /builds/slave/test/build
04:08:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
04:08:08 INFO - Running post-action listener: _resource_record_post_action
04:08:08 INFO - [mozharness: 2017-10-29 11:08:08.732289Z] Finished clobber step (success)
04:08:08 INFO - [mozharness: 2017-10-29 11:08:08.732386Z] Running read-buildbot-config step.
04:08:08 INFO - Running pre-action listener: _resource_record_pre_action
04:08:08 INFO - Running main action method: read_buildbot_config
04:08:08 INFO - Using buildbot properties:
04:08:08 INFO - {
04:08:08 INFO - "project": "",
04:08:08 INFO - "product": "firefox",
04:08:08 INFO - "who": "archaeopteryx@coole-files.de",
04:08:08 INFO - "installer_path": "public/build/target.tar.bz2",
04:08:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
04:08:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
04:08:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-stylo-disabled-e10s",
04:08:08 INFO - "stage_platform": "linux64",
04:08:08 INFO - "basedir": "/builds/slave/test",
04:08:08 INFO - "buildnumber": 129,
04:08:08 INFO - "platform": "ubuntu64_hw",
04:08:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
04:08:08 INFO - "slavebuilddir": "test",
04:08:08 INFO - "taskId": "DlWxu52ATae3RLfdbqDzYA",
04:08:08 INFO - "branch": "mozilla-central",
04:08:08 INFO - "script_repo_revision": "production",
04:08:08 INFO - "revision": "d58c5cb053be123a9ede85cabce3b060c13cf66d",
04:08:08 INFO - "slavename": "talos-linux64-ix-079",
04:08:08 INFO - "repo_path": "mozilla-central"
04:08:08 INFO - }
04:08:08 INFO - Finding installer, test and symbols from parent task.
04:08:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DlWxu52ATae3RLfdbqDzYA'}, attempt #1
04:08:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DlWxu52ATae3RLfdbqDzYA'}, attempt #1
04:08:09 INFO - Task dependencies: VWQYlIZWQT-XQiNkj0GI2g
04:08:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g'}, attempt #1
04:08:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2
04:08:10 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.test_packages.json
04:08:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.crashreporter-symbols.zip
04:08:10 INFO - Running post-action listener: _resource_record_post_action
04:08:10 INFO - [mozharness: 2017-10-29 11:08:10.084535Z] Finished read-buildbot-config step (success)
04:08:10 INFO - [mozharness: 2017-10-29 11:08:10.084751Z] Running download-and-extract step.
04:08:10 INFO - Running pre-action listener: _resource_record_pre_action
04:08:10 INFO - Running main action method: download_and_extract
04:08:10 INFO - mkdir: /builds/slave/test/build/tests
04:08:10 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')]}
04:08:10 INFO - trying https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.test_packages.json
04:08:10 INFO - Downloading https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
04:08:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
04:08:10 INFO - Downloaded 989 bytes.
04:08:10 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
04:08:10 INFO - Using the following test package requirements:
04:08:10 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
04:08:10 INFO - u'common': [u'target.common.tests.zip'],
04:08:10 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
04:08:10 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
04:08:10 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
04:08:10 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
04:08:10 INFO - u'mozbase': [u'target.common.tests.zip'],
04:08:10 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
04:08:10 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
04:08:10 INFO - u'web-platform': [u'target.common.tests.zip',
04:08:10 INFO - u'target.web-platform.tests.tar.gz'],
04:08:10 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
04:08:10 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
04:08:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.common.tests.zip
04:08:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.common.tests.zip'}, attempt #1
04:08:10 INFO - Fetch https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.common.tests.zip into memory
04:08:13 INFO - Content-Length response header: 56329924
04:08:13 INFO - Bytes received: 56329924
04:08:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.talos.tests.zip
04:08:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.talos.tests.zip'}, attempt #1
04:08:24 INFO - Fetch https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.talos.tests.zip into memory
04:08:26 INFO - Content-Length response header: 18770379
04:08:26 INFO - Bytes received: 18770379
04:08: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')]}
04:08:26 INFO - trying https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2
04:08:26 INFO - Downloading https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
04:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
04:08:30 INFO - Downloaded 62854600 bytes.
04:08:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2
04:08:30 INFO - mkdir: /builds/slave/test/properties
04:08:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
04:08:30 INFO - Writing to file /builds/slave/test/properties/build_url
04:08:30 INFO - Contents:
04:08:30 INFO - build_url:https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2
04:08:30 INFO - Running post-action listener: _resource_record_post_action
04:08:30 INFO - Running post-action listener: find_tests_for_verification
04:08:30 INFO - Running post-action listener: set_extra_try_arguments
04:08:30 INFO - [mozharness: 2017-10-29 11:08:30.142937Z] Finished download-and-extract step (success)
04:08:30 INFO - [mozharness: 2017-10-29 11:08:30.143160Z] Running populate-webroot step.
04:08:30 INFO - Running pre-action listener: _resource_record_pre_action
04:08:30 INFO - Running main action method: populate_webroot
04:08:30 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
04:08:30 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['tresize',
04:08:30 INFO - 'tcanvasmark']},
04:08:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
04:08:30 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['dromaeo_css',
04:08:30 INFO - 'kraken']},
04:08:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
04:08:30 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
04:08:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'tests': ['damp', 'tps']},
04:08:30 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['damp', 'tps']},
04:08:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
04:08:30 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['dromaeo_dom']},
04:08:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
04:08:30 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['basic_compositor_video',
04:08:30 INFO - 'glvideo']},
04:08:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
04:08:30 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['ts_paint_webext',
04:08:30 INFO - 'tp5o_webext']},
04:08:30 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
04:08:30 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:08:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:08:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:08:30 INFO - 'talos_options': ['--mitmproxy',
04:08:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:08:30 INFO - '--firstNonBlankPaint'],
04:08:30 INFO - 'tests': ['tp6_google_heavy',
04:08:30 INFO - 'tp6_youtube_heavy',
04:08:30 INFO - 'tp6_amazon_heavy',
04:08:30 INFO - 'tp6_facebook_heavy']},
04:08:30 INFO - 'other-e10s': {'tests': ['a11yr',
04:08:30 INFO - 'ts_paint',
04:08:30 INFO - 'tpaint',
04:08:30 INFO - 'sessionrestore',
04:08:30 INFO - 'sessionrestore_many_windows',
04:08:30 INFO - 'sessionrestore_no_auto_restore',
04:08:30 INFO - 'tabpaint',
04:08:30 INFO - 'cpstartup']},
04:08:30 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['a11yr',
04:08:30 INFO - 'ts_paint',
04:08:30 INFO - 'tpaint',
04:08:30 INFO - 'sessionrestore',
04:08:30 INFO - 'sessionrestore_many_windows',
04:08:30 INFO - 'sessionrestore_no_auto_restore',
04:08:30 INFO - 'tabpaint',
04:08:30 INFO - 'cpstartup']},
04:08:30 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
04:08:30 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
04:08:30 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['perf_reftest_singletons']},
04:08:30 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['perf_reftest']},
04:08:30 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
04:08:30 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['speedometer']},
04:08:30 INFO - 'svgr-e10s': {'tests': ['tsvgx',
04:08:30 INFO - 'tsvgr_opacity',
04:08:30 INFO - 'tart',
04:08:30 INFO - 'tscrollx',
04:08:30 INFO - 'tsvg_static']},
04:08:30 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['tsvgx',
04:08:30 INFO - 'tsvgr_opacity',
04:08:30 INFO - 'tart',
04:08:30 INFO - 'tscrollx',
04:08:30 INFO - 'tsvg_static']},
04:08:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
04:08:30 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'talos_options': ['--disable-stylo'],
04:08:30 INFO - 'tests': ['tp5o']},
04:08:30 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:08:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:08:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:08:30 INFO - 'talos_options': ['--mitmproxy',
04:08:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:08:30 INFO - '--firstNonBlankPaint'],
04:08:30 INFO - 'tests': ['tp6_google',
04:08:30 INFO - 'tp6_youtube',
04:08:30 INFO - 'tp6_amazon',
04:08:30 INFO - 'tp6_facebook']},
04:08:30 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:08:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:08:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:08:30 INFO - 'talos_options': ['--disable-stylo',
04:08:30 INFO - '--mitmproxy',
04:08:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:08:30 INFO - '--firstNonBlankPaint'],
04:08:30 INFO - 'tests': ['tp6_google',
04:08:30 INFO - 'tp6_youtube',
04:08:30 INFO - 'tp6_amazon',
04:08:30 INFO - 'tp6_facebook']},
04:08:30 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:08:30 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:08:30 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:08:30 INFO - 'talos_options': ['--stylo-threads=1',
04:08:30 INFO - '--mitmproxy',
04:08:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:08:30 INFO - '--firstNonBlankPaint'],
04:08:30 INFO - 'tests': ['tp6_google',
04:08:30 INFO - 'tp6_youtube',
04:08:30 INFO - 'tp6_amazon',
04:08:30 INFO - 'tp6_facebook']},
04:08:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'talos_options': ['--xperf_path',
04:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
04:08:30 INFO - 'tests': ['tp5n']},
04:08:30 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:08:30 INFO - 'talos_options': ['--disable-stylo',
04:08:30 INFO - '--xperf_path',
04:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
04:08:30 INFO - 'tests': ['tp5n']}}}
04:08:30 INFO - Running post-action listener: _resource_record_post_action
04:08:30 INFO - [mozharness: 2017-10-29 11:08:30.167568Z] Finished populate-webroot step (success)
04:08:30 INFO - [mozharness: 2017-10-29 11:08:30.167656Z] Running create-virtualenv step.
04:08:30 INFO - Running pre-action listener: _resource_record_pre_action
04:08:30 INFO - Running main action method: create_virtualenv
04:08:30 INFO - Creating virtualenv /builds/slave/test/build/venv
04:08:30 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
04:08:30 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
04:08:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
04:08:30 INFO - Using real prefix '/usr'
04:08:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python
04:08:30 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
04:08:32 INFO - Installing setuptools, pip, wheel...done.
04:08:32 INFO - Return code: 0
04:08:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
04:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
04:08:32 INFO - Using env: {'DISPLAY': ':0',
04:08:32 INFO - 'HOME': '/home/cltbld',
04:08:32 INFO - 'LANG': 'en_US.UTF-8',
04:08:32 INFO - 'LANGUAGE': 'en_US:en',
04:08:32 INFO - 'LOGNAME': 'cltbld',
04:08:32 INFO - 'MAIL': '/var/mail/cltbld',
04:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:32 INFO - 'MOZ_NO_REMOTE': '1',
04:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:32 INFO - 'NO_EM_RESTART': '1',
04:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:32 INFO - 'PWD': '/builds/slave/test',
04:08:32 INFO - 'SHELL': '/bin/bash',
04:08:32 INFO - 'SHLVL': '1',
04:08:32 INFO - 'TERM': 'linux',
04:08:32 INFO - 'TMOUT': '86400',
04:08:32 INFO - 'USER': 'cltbld',
04:08:32 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:32 INFO - '_': '/tools/buildbot/bin/python'}
04:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
04:08:32 INFO - Return code: 0
04:08:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
04:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
04:08:32 INFO - Using env: {'DISPLAY': ':0',
04:08:32 INFO - 'HOME': '/home/cltbld',
04:08:32 INFO - 'LANG': 'en_US.UTF-8',
04:08:32 INFO - 'LANGUAGE': 'en_US:en',
04:08:32 INFO - 'LOGNAME': 'cltbld',
04:08:32 INFO - 'MAIL': '/var/mail/cltbld',
04:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:32 INFO - 'MOZ_NO_REMOTE': '1',
04:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:32 INFO - 'NO_EM_RESTART': '1',
04:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:32 INFO - 'PWD': '/builds/slave/test',
04:08:32 INFO - 'SHELL': '/bin/bash',
04:08:32 INFO - 'SHLVL': '1',
04:08:32 INFO - 'TERM': 'linux',
04:08:32 INFO - 'TMOUT': '86400',
04:08:32 INFO - 'USER': 'cltbld',
04:08:32 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:32 INFO - '_': '/tools/buildbot/bin/python'}
04:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:33 INFO - Collecting psutil>=3.1.1
04:08:34 INFO - Installing collected packages: psutil
04:08:34 INFO - Successfully installed psutil-3.1.1
04:08:34 INFO - Return code: 0
04:08:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
04:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
04:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
04:08:34 INFO - Using env: {'DISPLAY': ':0',
04:08:34 INFO - 'HOME': '/home/cltbld',
04:08:34 INFO - 'LANG': 'en_US.UTF-8',
04:08:34 INFO - 'LANGUAGE': 'en_US:en',
04:08:34 INFO - 'LOGNAME': 'cltbld',
04:08:34 INFO - 'MAIL': '/var/mail/cltbld',
04:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:34 INFO - 'MOZ_NO_REMOTE': '1',
04:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:34 INFO - 'NO_EM_RESTART': '1',
04:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:34 INFO - 'PWD': '/builds/slave/test',
04:08:34 INFO - 'SHELL': '/bin/bash',
04:08:34 INFO - 'SHLVL': '1',
04:08:34 INFO - 'TERM': 'linux',
04:08:34 INFO - 'TMOUT': '86400',
04:08:34 INFO - 'USER': 'cltbld',
04:08:34 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:34 INFO - '_': '/tools/buildbot/bin/python'}
04:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:34 INFO - Collecting mozsystemmonitor==0.3
04:08:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
04:08:35 INFO - Installing collected packages: mozsystemmonitor
04:08:35 INFO - Successfully installed mozsystemmonitor-0.3
04:08:35 INFO - Return code: 0
04:08:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
04:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
04:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
04:08:35 INFO - Using env: {'DISPLAY': ':0',
04:08:35 INFO - 'HOME': '/home/cltbld',
04:08:35 INFO - 'LANG': 'en_US.UTF-8',
04:08:35 INFO - 'LANGUAGE': 'en_US:en',
04:08:35 INFO - 'LOGNAME': 'cltbld',
04:08:35 INFO - 'MAIL': '/var/mail/cltbld',
04:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:35 INFO - 'MOZ_NO_REMOTE': '1',
04:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:35 INFO - 'NO_EM_RESTART': '1',
04:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:35 INFO - 'PWD': '/builds/slave/test',
04:08:35 INFO - 'SHELL': '/bin/bash',
04:08:35 INFO - 'SHLVL': '1',
04:08:35 INFO - 'TERM': 'linux',
04:08:35 INFO - 'TMOUT': '86400',
04:08:35 INFO - 'USER': 'cltbld',
04:08:35 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:35 INFO - '_': '/tools/buildbot/bin/python'}
04:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:36 INFO - Collecting jsonschema==2.5.1
04:08:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
04:08:37 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
04:08:38 INFO - Installing collected packages: functools32, jsonschema
04:08:38 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
04:08:38 INFO - Return code: 0
04:08:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
04:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
04:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
04:08:38 INFO - Using env: {'DISPLAY': ':0',
04:08:38 INFO - 'HOME': '/home/cltbld',
04:08:38 INFO - 'LANG': 'en_US.UTF-8',
04:08:38 INFO - 'LANGUAGE': 'en_US:en',
04:08:38 INFO - 'LOGNAME': 'cltbld',
04:08:38 INFO - 'MAIL': '/var/mail/cltbld',
04:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:38 INFO - 'MOZ_NO_REMOTE': '1',
04:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:38 INFO - 'NO_EM_RESTART': '1',
04:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:38 INFO - 'PWD': '/builds/slave/test',
04:08:38 INFO - 'SHELL': '/bin/bash',
04:08:38 INFO - 'SHLVL': '1',
04:08:38 INFO - 'TERM': 'linux',
04:08:38 INFO - 'TMOUT': '86400',
04:08:38 INFO - 'USER': 'cltbld',
04:08:38 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:38 INFO - '_': '/tools/buildbot/bin/python'}
04:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
04:08:38 INFO - Return code: 0
04:08:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
04:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
04:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
04:08:38 INFO - Using env: {'DISPLAY': ':0',
04:08:38 INFO - 'HOME': '/home/cltbld',
04:08:38 INFO - 'LANG': 'en_US.UTF-8',
04:08:38 INFO - 'LANGUAGE': 'en_US:en',
04:08:38 INFO - 'LOGNAME': 'cltbld',
04:08:38 INFO - 'MAIL': '/var/mail/cltbld',
04:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:38 INFO - 'MOZ_NO_REMOTE': '1',
04:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:38 INFO - 'NO_EM_RESTART': '1',
04:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:38 INFO - 'PWD': '/builds/slave/test',
04:08:38 INFO - 'SHELL': '/bin/bash',
04:08:38 INFO - 'SHLVL': '1',
04:08:38 INFO - 'TERM': 'linux',
04:08:38 INFO - 'TMOUT': '86400',
04:08:38 INFO - 'USER': 'cltbld',
04:08:38 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:38 INFO - '_': '/tools/buildbot/bin/python'}
04:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:39 INFO - Collecting blobuploader==1.2.4
04:08:40 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
04:08:41 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
04:08:42 INFO - Installing collected packages: requests, docopt, blobuploader
04:08:42 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
04:08:42 INFO - Return code: 0
04:08:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:08:42 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')]}
04:08:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:42 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')]}
04:08:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:42 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 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:42 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
04:08:42 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
04:08:42 INFO - Using env: {'DISPLAY': ':0',
04:08:42 INFO - 'HOME': '/home/cltbld',
04:08:42 INFO - 'LANG': 'en_US.UTF-8',
04:08:42 INFO - 'LANGUAGE': 'en_US:en',
04:08:42 INFO - 'LOGNAME': 'cltbld',
04:08:42 INFO - 'MAIL': '/var/mail/cltbld',
04:08:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:42 INFO - 'MOZ_NO_REMOTE': '1',
04:08:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:42 INFO - 'NO_EM_RESTART': '1',
04:08:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:42 INFO - 'PWD': '/builds/slave/test',
04:08:42 INFO - 'SHELL': '/bin/bash',
04:08:42 INFO - 'SHLVL': '1',
04:08:42 INFO - 'TERM': 'linux',
04:08:42 INFO - 'TMOUT': '86400',
04:08:42 INFO - 'USER': 'cltbld',
04:08:42 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:42 INFO - '_': '/tools/buildbot/bin/python'}
04:08:43 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:43 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
04:08:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
04:08:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
04:08:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
04:08:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
04:08:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
04:08:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
04:08:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
04:08:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
04:08:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
04:08:45 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
04:08:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
04:08:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
04:08:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
04:08:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
04:08:46 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
04:08:46 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
04:08:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
04:08:46 INFO - Running setup.py install for manifestparser: started
04:08:46 INFO - Running setup.py install for manifestparser: finished with status 'done'
04:08:46 INFO - Running setup.py install for mozcrash: started
04:08:46 INFO - Running setup.py install for mozcrash: finished with status 'done'
04:08:46 INFO - Running setup.py install for mozdebug: started
04:08:47 INFO - Running setup.py install for mozdebug: finished with status 'done'
04:08:47 INFO - Running setup.py install for mozdevice: started
04:08:47 INFO - Running setup.py install for mozdevice: finished with status 'done'
04:08:47 INFO - Running setup.py install for mozfile: started
04:08:47 INFO - Running setup.py install for mozfile: finished with status 'done'
04:08:47 INFO - Running setup.py install for mozhttpd: started
04:08:47 INFO - Running setup.py install for mozhttpd: finished with status 'done'
04:08:47 INFO - Running setup.py install for mozinfo: started
04:08:47 INFO - Running setup.py install for mozinfo: finished with status 'done'
04:08:47 INFO - Running setup.py install for mozInstall: started
04:08:48 INFO - Running setup.py install for mozInstall: finished with status 'done'
04:08:48 INFO - Running setup.py install for mozleak: started
04:08:48 INFO - Running setup.py install for mozleak: finished with status 'done'
04:08:48 INFO - Running setup.py install for mozlog: started
04:08:48 INFO - Running setup.py install for mozlog: finished with status 'done'
04:08:48 INFO - Running setup.py install for moznetwork: started
04:08:48 INFO - Running setup.py install for moznetwork: finished with status 'done'
04:08:48 INFO - Running setup.py install for mozprocess: started
04:08:49 INFO - Running setup.py install for mozprocess: finished with status 'done'
04:08:49 INFO - Running setup.py install for mozprofile: started
04:08:49 INFO - Running setup.py install for mozprofile: finished with status 'done'
04:08:49 INFO - Running setup.py install for mozrunner: started
04:08:49 INFO - Running setup.py install for mozrunner: finished with status 'done'
04:08:49 INFO - Running setup.py install for mozscreenshot: started
04:08:49 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
04:08:49 INFO - Running setup.py install for moztest: started
04:08:49 INFO - Running setup.py install for moztest: finished with status 'done'
04:08:49 INFO - Running setup.py install for mozversion: started
04:08:50 INFO - Running setup.py install for mozversion: finished with status 'done'
04:08:50 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
04:08:50 INFO - Return code: 0
04:08:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:08:50 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')]}
04:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:50 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')]}
04:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:50 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 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:50 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
04:08:50 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
04:08:50 INFO - Using env: {'DISPLAY': ':0',
04:08:50 INFO - 'HOME': '/home/cltbld',
04:08:50 INFO - 'LANG': 'en_US.UTF-8',
04:08:50 INFO - 'LANGUAGE': 'en_US:en',
04:08:50 INFO - 'LOGNAME': 'cltbld',
04:08:50 INFO - 'MAIL': '/var/mail/cltbld',
04:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:50 INFO - 'MOZ_NO_REMOTE': '1',
04:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:50 INFO - 'NO_EM_RESTART': '1',
04:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:50 INFO - 'PWD': '/builds/slave/test',
04:08:50 INFO - 'SHELL': '/bin/bash',
04:08:50 INFO - 'SHLVL': '1',
04:08:50 INFO - 'TERM': 'linux',
04:08:50 INFO - 'TMOUT': '86400',
04:08:50 INFO - 'USER': 'cltbld',
04:08:50 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:50 INFO - '_': '/tools/buildbot/bin/python'}
04:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:50 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
04:08:51 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))
04:08:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
04:08:51 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))
04:08:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
04:08:51 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))
04:08:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
04:08:51 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))
04:08:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
04:08:51 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))
04:08:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
04:08:52 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))
04:08:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
04:08:52 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))
04:08:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
04:08:52 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))
04:08:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
04:08:52 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))
04:08:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
04:08:52 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))
04:08:52 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
04:08:53 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))
04:08:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
04:08:53 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))
04:08:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
04:08:53 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))
04:08:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
04:08:53 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))
04:08:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
04:08:53 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))
04:08:53 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
04:08:54 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))
04:08:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
04:08:54 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))
04:08:54 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
04:08:55 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
04:08:55 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))
04:08:55 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))
04:08:55 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))
04:08:55 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))
04:08:55 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))
04:08:55 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))
04:08:55 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
04:08:56 INFO - Installing collected packages: six, blessings
04:08:56 INFO - Successfully installed blessings-1.6 six-1.10.0
04:08:56 INFO - Return code: 0
04:08:56 INFO - Done creating virtualenv /builds/slave/test/build/venv.
04:08:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
04:08:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
04:08:57 INFO - Reading from file tmpfile_stdout
04:08:57 INFO - Reading from file tmpfile_stderr
04:08:57 INFO - Current package versions:
04:08:57 INFO - blessings == 1.6
04:08:57 INFO - blobuploader == 1.2.4
04:08:57 INFO - docopt == 0.6.1
04:08:57 INFO - functools32 == 3.2.3.post2
04:08:57 INFO - jsonschema == 2.5.1
04:08:57 INFO - manifestparser == 1.2
04:08:57 INFO - mozInstall == 1.14
04:08:57 INFO - mozcrash == 1.0
04:08:57 INFO - mozdebug == 0.1
04:08:57 INFO - mozdevice == 0.51
04:08:57 INFO - mozfile == 1.2
04:08:57 INFO - mozhttpd == 0.7
04:08:57 INFO - mozinfo == 0.10
04:08:57 INFO - mozleak == 0.1
04:08:57 INFO - mozlog == 3.5
04:08:57 INFO - moznetwork == 0.27
04:08:57 INFO - mozprocess == 0.25
04:08:57 INFO - mozprofile == 0.29
04:08:57 INFO - mozrunner == 6.13
04:08:57 INFO - mozscreenshot == 0.1
04:08:57 INFO - mozsystemmonitor == 0.3
04:08:57 INFO - moztest == 0.8
04:08:57 INFO - mozversion == 1.4
04:08:57 INFO - psutil == 3.1.1
04:08:57 INFO - requests == 1.2.3
04:08:57 INFO - six == 1.10.0
04:08:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:08:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:08:57 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 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:08:57 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
04:08:57 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
04:08:57 INFO - Using env: {'DISPLAY': ':0',
04:08:57 INFO - 'HOME': '/home/cltbld',
04:08:57 INFO - 'LANG': 'en_US.UTF-8',
04:08:57 INFO - 'LANGUAGE': 'en_US:en',
04:08:57 INFO - 'LOGNAME': 'cltbld',
04:08:57 INFO - 'MAIL': '/var/mail/cltbld',
04:08:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:08:57 INFO - 'MOZ_NO_REMOTE': '1',
04:08:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:08:57 INFO - 'NO_EM_RESTART': '1',
04:08:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:08:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:08:57 INFO - 'PWD': '/builds/slave/test',
04:08:57 INFO - 'SHELL': '/bin/bash',
04:08:57 INFO - 'SHLVL': '1',
04:08:57 INFO - 'TERM': 'linux',
04:08:57 INFO - 'TMOUT': '86400',
04:08:57 INFO - 'USER': 'cltbld',
04:08:57 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:08:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:08:57 INFO - '_': '/tools/buildbot/bin/python'}
04:08:57 INFO - Ignoring indexes: https://pypi.python.org/simple
04:08:57 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))
04:08:57 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))
04:08:57 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))
04:08:57 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))
04:08:57 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))
04:08:57 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))
04:08:57 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))
04:08:57 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))
04:08:57 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))
04:08:57 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
04:08:58 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
04:08:59 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))
04:08:59 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))
04:08:59 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))
04:08:59 INFO - Installing collected packages: simplejson, requests
04:08:59 INFO - Found existing installation: requests 1.2.3
04:08:59 INFO - Uninstalling requests-1.2.3:
04:08:59 INFO - Successfully uninstalled requests-1.2.3
04:09:00 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
04:09:00 INFO - Return code: 0
04:09:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
04:09: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')]}
04:09:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:09: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')]}
04:09:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c31f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x258ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2730cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, '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': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270', '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
04:09: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', 'jsonschema'] in /builds/slave/test/build
04:09: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 jsonschema
04:09:00 INFO - Using env: {'DISPLAY': ':0',
04:09:00 INFO - 'HOME': '/home/cltbld',
04:09:00 INFO - 'LANG': 'en_US.UTF-8',
04:09:00 INFO - 'LANGUAGE': 'en_US:en',
04:09:00 INFO - 'LOGNAME': 'cltbld',
04:09:00 INFO - 'MAIL': '/var/mail/cltbld',
04:09:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:09:00 INFO - 'MOZ_NO_REMOTE': '1',
04:09:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:09:00 INFO - 'NO_EM_RESTART': '1',
04:09:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:09:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:09:00 INFO - 'PWD': '/builds/slave/test',
04:09:00 INFO - 'SHELL': '/bin/bash',
04:09:00 INFO - 'SHLVL': '1',
04:09:00 INFO - 'TERM': 'linux',
04:09:00 INFO - 'TMOUT': '86400',
04:09:00 INFO - 'USER': 'cltbld',
04:09:00 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:09:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:09:00 INFO - '_': '/tools/buildbot/bin/python'}
04:09:00 INFO - Ignoring indexes: https://pypi.python.org/simple
04:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
04:09:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
04:09:00 INFO - Return code: 0
04:09:00 INFO - Running post-action listener: _resource_record_post_action
04:09:00 INFO - Running post-action listener: _start_resource_monitoring
04:09:00 INFO - Starting resource monitoring.
04:09:00 INFO - [mozharness: 2017-10-29 11:09:00.723133Z] Finished create-virtualenv step (success)
04:09:00 INFO - [mozharness: 2017-10-29 11:09:00.723667Z] Running install step.
04:09:00 INFO - Running pre-action listener: _resource_record_pre_action
04:09:00 INFO - Running main action method: install
04:09:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
04:09:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
04:09:01 INFO - Reading from file tmpfile_stdout
04:09:01 INFO - Reading from file tmpfile_stderr
04:09:01 INFO - Detecting whether we're running mozinstall >=1.0...
04:09:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
04:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
04:09:01 INFO - Reading from file tmpfile_stdout
04:09:01 INFO - Output received:
04:09:01 INFO - Usage: mozinstall [options] installer
04:09:01 INFO - Options:
04:09:01 INFO - -h, --help show this help message and exit
04:09:01 INFO - -d DEST, --destination=DEST
04:09:01 INFO - Directory to install application into. [default:
04:09:01 INFO - "/builds/slave/test"]
04:09:01 INFO - --app=APP Application being installed. [default: firefox]
04:09:01 INFO - mkdir: /builds/slave/test/build/application
04:09:01 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']
04:09:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
04:09:19 INFO - Reading from file tmpfile_stdout
04:09:19 INFO - Output received:
04:09:19 INFO - /builds/slave/test/build/application/firefox/firefox
04:09:19 INFO - Running post-action listener: _resource_record_post_action
04:09:19 INFO - [mozharness: 2017-10-29 11:09:19.616858Z] Finished install step (success)
04:09:19 INFO - [mozharness: 2017-10-29 11:09:19.616979Z] Running setup-mitmproxy step.
04:09:19 INFO - Running pre-action listener: _resource_record_pre_action
04:09:19 INFO - Running main action method: setup_mitmproxy
04:09:19 INFO - Skipping: mitmproxy is not required
04:09:19 INFO - Running post-action listener: _resource_record_post_action
04:09:19 INFO - [mozharness: 2017-10-29 11:09:19.618000Z] Finished setup-mitmproxy step (success)
04:09:19 INFO - [mozharness: 2017-10-29 11:09:19.618222Z] Running run-tests step.
04:09:19 INFO - Running pre-action listener: _resource_record_pre_action
04:09:19 INFO - Running pre-action listener: _set_gcov_prefix
04:09:19 INFO - Running main action method: run_tests
04:09:19 WARNING - Try message not found.
04:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
04:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
04:09:19 INFO - Python 2.7.3
04:09:19 INFO - Return code: 0
04:09:19 INFO - grabbing minidump binary from tooltool
04:09:19 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')]}
04:09:19 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 0x272de60>, '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 0x272ed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x272f1f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
04:09:19 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
04:09:19 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
04:09:19 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
04:09:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
04:09:19 INFO - Return code: 0
04:09:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
04:09:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
04:09:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
04:09:19 INFO - ENV: RUST_BACKTRACE is now full
04:09:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
04:09:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
04:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build
04:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-079 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log
04:09:19 INFO - Using env: {'DISPLAY': ':0',
04:09:19 INFO - 'HOME': '/home/cltbld',
04:09:19 INFO - 'LANG': 'en_US.UTF-8',
04:09:19 INFO - 'LANGUAGE': 'en_US:en',
04:09:19 INFO - 'LOGNAME': 'cltbld',
04:09:19 INFO - 'MAIL': '/var/mail/cltbld',
04:09:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:09:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
04:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:09:19 INFO - 'MOZ_NO_REMOTE': '1',
04:09:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:09:19 INFO - 'NO_EM_RESTART': '1',
04:09:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:09:19 INFO - 'PWD': '/builds/slave/test',
04:09:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:09:19 INFO - 'RUST_BACKTRACE': 'full',
04:09:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
04:09:19 INFO - 'SHELL': '/bin/bash',
04:09:19 INFO - 'SHLVL': '1',
04:09:19 INFO - 'TERM': 'linux',
04:09:19 INFO - 'TMOUT': '86400',
04:09:19 INFO - 'USER': 'cltbld',
04:09:19 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:09:19 INFO - '_': '/tools/buildbot/bin/python'}
04:09:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-079', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] with output_timeout 3600
04:09:20 INFO - mozversion application_buildid: 20171029100142
04:09:20 INFO - mozversion application_changeset: d58c5cb053be123a9ede85cabce3b060c13cf66d
04:09:20 INFO - mozversion application_display_name: Nightly
04:09:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
04:09:20 INFO - mozversion application_name: Firefox
04:09:20 INFO - mozversion application_remotingname: firefox
04:09:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
04:09:20 INFO - mozversion application_vendor: Mozilla
04:09:20 INFO - mozversion application_version: 58.0a1
04:09:20 INFO - mozversion platform_buildid: 20171029100142
04:09:20 INFO - mozversion platform_changeset: d58c5cb053be123a9ede85cabce3b060c13cf66d
04:09:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
04:09:20 INFO - mozversion platform_version: 58.0a1
04:09:20 INFO - using testdate: 1509275360
04:09:20 INFO - actual date: 1509275360
04:09:20 INFO - starting webserver on 'localhost:40234'
04:09:20 INFO - SUITE-START | Running 2 tests
04:09:20 INFO - TEST-START | basic_compositor_video
04:09:20 INFO - Initialising browser for basic_compositor_video test...
04:09:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40234/getInfo.html -profile /tmp/tmpPLJcRW/profile
04:09:20 INFO - TEST-INFO | started process 17164 (/builds/slave/test/build/application/firefox/firefox http://localhost:40234/getInfo.html)
04:09:26 INFO - TEST-INFO | 17164: exit 0
04:09:26 INFO - Browser initialized.
04:09:26 INFO - Running cycle 1/1 for basic_compositor_video test...
04:09:26 INFO - Using env: {'DISPLAY': ':0',
04:09:26 INFO - 'HOME': '/home/cltbld',
04:09:26 INFO - 'JSGC_DISABLE_POISONING': '1',
04:09:26 INFO - 'LANG': 'en_US.UTF-8',
04:09:26 INFO - 'LANGUAGE': 'en_US:en',
04:09:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
04:09:26 INFO - 'LOGNAME': 'cltbld',
04:09:26 INFO - 'MAIL': '/var/mail/cltbld',
04:09:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:09:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
04:09:26 INFO - 'MOZ_CRASHREPORTER': '1',
04:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:09:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
04:09:26 INFO - 'MOZ_NO_REMOTE': '1',
04:09:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:09:26 INFO - 'NO_EM_RESTART': '1',
04:09:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:09:26 INFO - 'PWD': '/builds/slave/test',
04:09:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:09:26 INFO - 'RUST_BACKTRACE': 'full',
04:09:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
04:09:26 INFO - 'SHELL': '/bin/bash',
04:09:26 INFO - 'SHLVL': '1',
04:09:26 INFO - 'STYLO_FORCE_DISABLED': '1',
04:09:26 INFO - 'TERM': 'linux',
04:09:26 INFO - 'TMOUT': '86400',
04:09:26 INFO - 'USER': 'cltbld',
04:09:26 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:09:26 INFO - '_': '/tools/buildbot/bin/python'}
04:09:26 INFO - TEST-INFO | started process 17435 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPLJcRW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
04:09:27 INFO - PID 17435 |
04:09:27 INFO - PID 17435 | (/builds/slave/test/build/application/firefox/firefox:17486): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
04:09:27 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.689552364864865 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684778982485409 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.782694592988711 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7347441457068515 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.121531593406594 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.221529535864978 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.6156746987951833 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.553126110124334 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.292918871252209 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.747399425287349 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 9.9693853820598 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 8.889266666666662 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.765431372549024 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.994120879120882 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398533057851239 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.911369047619008 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49754789272031 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.176899441340787 ms/frame
04:10:13 INFO - PID 17435 |
04:10:13 INFO - PID 17435 | Cycle 1(1): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:10:59 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.695310734463277 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6711319966583122 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8045460012026457 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.748461538461539 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.038499327052489 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0988422131147555 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.6507785888077886 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5846146953404956 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.884098039215685 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.731117478510028 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 9.74366883116883 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 9.574066985645931 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.494712643678158 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.116500000000006 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.451431535269732 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.978353293413143 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.768627450980393 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.303248587570637 ms/frame
04:10:59 INFO - PID 17435 |
04:10:59 INFO - PID 17435 | Cycle 1(2): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:11:45 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6849859629421673 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687114261884908 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7957749850388987 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.750389326334208 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.12770976616231 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.057494929006084 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.614885542168674 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.509254385964915 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.703707224334604 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603501400560226 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 9.804313725490193 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 10.051507537688442 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.632248062015513 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.93245901639344 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398842975206628 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977065868263463 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584247104247103 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.114749999999994 ms/frame
04:11:45 INFO - PID 17435 |
04:11:45 INFO - PID 17435 | Cycle 1(3): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:12:32 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827902411665732 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6752973199329981 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8316697191697189 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7844424620874224 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.214543539325844 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.150674273858921 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.6238768115942004 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6103429602888104 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.609336448598129 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.748778735632186 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 9.871381578947373 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 10.20806122448979 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.585019305019307 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.05110497237569 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.55656903765689 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053463855421686 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.767686274509812 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.301045197740091 ms/frame
04:12:32 INFO - PID 17435 |
04:12:32 INFO - PID 17435 | Cycle 1(4): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:13:18 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6902845070422536 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6755946398659964 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7883402860548272 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7876050044682752 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.323270893371759 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.282880085653104 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.610180505415162 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.491404886561956 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7701057692307725 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.700000000000002 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 10.17138983050848 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 10.256435897435903 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.674182879377444 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.236713483146039 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.662742616033754 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90755952380953 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.813385826771647 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.236601123595495 ms/frame
04:13:18 INFO - PID 17435 |
04:13:18 INFO - PID 17435 | Cycle 1(5): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:14:04 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6830089736399325 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6740711297071131 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7922550776583033 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7756122448979599 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.273874643874644 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.202237394957982 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.5885705741626817 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.491998254799297 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.737906309751432 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5248480662983495 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 10.103316498316502 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 10.003474999999998 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.54303846153847 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.053259668508284 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.553138075313825 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.19771341463412 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.721210937500018 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.116222222222213 ms/frame
04:14:04 INFO - PID 17435 |
04:14:04 INFO - PID 17435 | Cycle 1(6): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:14:27 INFO - PID 17435 |
04:14:27 INFO - PID 17435 | (/builds/slave/test/build/application/firefox/firefox:17565): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
04:14:27 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6846996069623807 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6836237373737377 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8321764346764344 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7798131672597859 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.178962395543176 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.115905349794239 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.645388821385177 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.572410714285713 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.61874531835206 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.291891534391541 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 9.319937888198758 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 9.484028436018962 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.720957031249995 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.30248587570622 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.931724137931019 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.127272727272727 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.67496108949415 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.302768361581942 ms/frame
04:14:50 INFO - PID 17435 |
04:14:50 INFO - PID 17435 | Cycle 1(7): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:15:36 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6802603583426652 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6717794486215538 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8079096385542168 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7819011576135357 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.256723404255319 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.099969262295081 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.7085414091470965 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.590897666068218 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.826699029126214 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.651878531073445 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 9.836967213114747 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 9.570693779904314 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.630038759689919 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.117638888888905 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.45128630705395 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97700598802393 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.812539370078719 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11522222222225 ms/frame
04:15:36 INFO - PID 17435 |
04:15:36 INFO - PID 17435 | Cycle 1(8): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:16:22 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6867903316469928 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6812647058823529 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7987350119904078 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7909444941808421 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.329300144300144 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.2122210526315795 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.6814478527607375 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5781395348837215 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.83772373540856 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6997863247863245 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 10.172355932203393 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 9.263217592592591 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.497413793103437 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.87230978260872 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.608109243697493 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.423757763975146 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.718925781249993 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.363948863636365 ms/frame
04:16:22 INFO - PID 17435 |
04:16:22 INFO - PID 17435 | Cycle 1(9): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:17:09 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6791158365976497 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6676374999999999 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8133323262839878 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7591468777484605 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1500276625172905 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.168874999999999 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.5846236559139806 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5090701754385933 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.965636182902585 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.8670087976539635 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 10.00031666666667 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 10.000299999999989 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.769392156862729 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.113250000000011 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.820619658119664 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05421686746988 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.720078125000015 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.116805555555564 ms/frame
04:17:09 INFO - PID 17435 |
04:17:09 INFO - PID 17435 | Cycle 1(10): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:17:55 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6949689265536725 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6737112970711296 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8086558167570828 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.8137579329102453 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 4.070658073270015 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.026106639839031 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.567241379310346 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4620761245674707 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619597378277157 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.666713881019826 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 10.03613712374582 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 10.205867346938769 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.58368725868727 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.306045197740103 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.047956521739126 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.836449704142016 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.812342519685036 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.628226744186048 ms/frame
04:17:55 INFO - PID 17435 |
04:17:55 INFO - PID 17435 | Cycle 1(11): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:18:41 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_startup = 1.683008973639933 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6771877619446771 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7906533412887828 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7637433862433862 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_startup = 3.812369758576873 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.002060000000001 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_startup = 3.709239802224971 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1_inclip = 3.571553571428571 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.758675623800385 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6191853932584275 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_startup = 9.839688524590162 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.480p.60fps.webm_scale_2_inclip = 9.43474056603774 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.539057692307702 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.993379120879112 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.556631799163164 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766705882352964 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.812559055118108 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.178072625698324 ms/frame
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | Cycle 1(12): loaded http://localhost:40234/tests/video/video_playback.html (next: http://localhost:40234/tests/video/video_playback.html)
04:18:41 INFO - PID 17435 | __start_tp_report
04:18:41 INFO - PID 17435 | _x_x_mozilla_page_load
04:18:41 INFO - PID 17435 | _x_x_mozilla_page_load_details
04:18:41 INFO - PID 17435 | |i|pagename|runs|
04:18:41 INFO - PID 17435 | |0;240p.120fps.mp4_scale_1_startup;1.689552364864865;1.695310734463277;1.6849859629421673;1.6827902411665732;1.6902845070422536;1.6830089736399325;1.6846996069623807;1.6802603583426652;1.6867903316469928;1.6791158365976497;1.6949689265536725;1.683008973639933
04:18:41 INFO - PID 17435 | |1;240p.120fps.mp4_scale_1_inclip;1.6684778982485409;1.6711319966583122;1.6687114261884908;1.6752973199329981;1.6755946398659964;1.6740711297071131;1.6836237373737377;1.6717794486215538;1.6812647058823529;1.6676374999999999;1.6737112970711296;1.6771877619446771
04:18:41 INFO - PID 17435 | |2;240p.120fps.mp4_scale_1.1_startup;1.782694592988711;1.8045460012026457;1.7957749850388987;1.8316697191697189;1.7883402860548272;1.7922550776583033;1.8321764346764344;1.8079096385542168;1.7987350119904078;1.8133323262839878;1.8086558167570828;1.7906533412887828
04:18:41 INFO - PID 17435 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7347441457068515;1.748461538461539;1.750389326334208;1.7844424620874224;1.7876050044682752;1.7756122448979599;1.7798131672597859;1.7819011576135357;1.7909444941808421;1.7591468777484605;1.8137579329102453;1.7637433862433862
04:18:41 INFO - PID 17435 | |4;240p.120fps.mp4_scale_2_startup;4.121531593406594;4.038499327052489;4.12770976616231;4.214543539325844;4.323270893371759;4.273874643874644;4.178962395543176;4.256723404255319;4.329300144300144;4.1500276625172905;4.070658073270015;3.812369758576873
04:18:41 INFO - PID 17435 | |5;240p.120fps.mp4_scale_2_inclip;4.221529535864978;4.0988422131147555;4.057494929006084;4.150674273858921;4.282880085653104;4.202237394957982;4.115905349794239;4.099969262295081;4.2122210526315795;4.168874999999999;4.026106639839031;4.002060000000001
04:18:41 INFO - PID 17435 | |6;480p.60fps.webm_scale_1_startup;3.6156746987951833;3.6507785888077886;3.614885542168674;3.6238768115942004;3.610180505415162;3.5885705741626817;3.645388821385177;3.7085414091470965;3.6814478527607375;3.5846236559139806;3.567241379310346;3.709239802224971
04:18:41 INFO - PID 17435 | |7;480p.60fps.webm_scale_1_inclip;3.553126110124334;3.5846146953404956;3.509254385964915;3.6103429602888104;3.491404886561956;3.491998254799297;3.572410714285713;3.590897666068218;3.5781395348837215;3.5090701754385933;3.4620761245674707;3.571553571428571
04:18:41 INFO - PID 17435 | |8;480p.60fps.webm_scale_1.1_startup;5.292918871252209;5.884098039215685;5.703707224334604;5.609336448598129;5.7701057692307725;5.737906309751432;5.61874531835206;5.826699029126214;5.83772373540856;5.965636182902585;5.619597378277157;5.758675623800385
04:18:41 INFO - PID 17435 | |9;480p.60fps.webm_scale_1.1_inclip;5.747399425287349;5.731117478510028;5.603501400560226;5.748778735632186;5.700000000000002;5.5248480662983495;5.291891534391541;5.651878531073445;5.6997863247863245;5.8670087976539635;5.666713881019826;5.6191853932584275
04:18:41 INFO - PID 17435 | |10;480p.60fps.webm_scale_2_startup;9.9693853820598;9.74366883116883;9.804313725490193;9.871381578947373;10.17138983050848;10.103316498316502;9.319937888198758;9.836967213114747;10.172355932203393;10.00031666666667;10.03613712374582;9.839688524590162
04:18:41 INFO - PID 17435 | |11;480p.60fps.webm_scale_2_inclip;8.889266666666662;9.574066985645931;10.051507537688442;10.20806122448979;10.256435897435903;10.003474999999998;9.484028436018962;9.570693779904314;9.263217592592591;10.000299999999989;10.205867346938769;9.43474056603774
04:18:41 INFO - PID 17435 | |12;1080p.60fps.mp4_scale_1_startup;11.765431372549024;11.494712643678158;11.632248062015513;11.585019305019307;11.674182879377444;11.54303846153847;11.720957031249995;11.630038759689919;11.497413793103437;11.769392156862729;11.58368725868727;11.539057692307702
04:18:41 INFO - PID 17435 | |13;1080p.60fps.mp4_scale_1_inclip;10.994120879120882;11.116500000000006;10.93245901639344;11.05110497237569;11.236713483146039;11.053259668508284;11.30248587570622;11.117638888888905;10.87230978260872;11.113250000000011;11.306045197740103;10.993379120879112
04:18:41 INFO - PID 17435 | |14;1080p.60fps.mp4_scale_1.1_startup;12.398533057851239;12.451431535269732;12.398842975206628;12.55656903765689;12.662742616033754;12.553138075313825;12.931724137931019;12.45128630705395;12.608109243697493;12.820619658119664;13.047956521739126;12.556631799163164
04:18:41 INFO - PID 17435 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.911369047619008;11.978353293413143;11.977065868263463;12.053463855421686;11.90755952380953;12.19771341463412;12.127272727272727;11.97700598802393;12.423757763975146;12.05421686746988;11.836449704142016;11.766705882352964
04:18:41 INFO - PID 17435 | |16;1080p.60fps.mp4_scale_2_startup;11.49754789272031;11.768627450980393;11.584247104247103;11.767686274509812;11.813385826771647;11.721210937500018;11.67496108949415;11.812539370078719;11.718925781249993;11.720078125000015;11.812342519685036;11.812559055118108
04:18:41 INFO - PID 17435 | |17;1080p.60fps.mp4_scale_2_inclip;11.176899441340787;11.303248587570637;11.114749999999994;11.301045197740091;11.236601123595495;11.116222222222213;11.302768361581942;11.11522222222225;11.363948863636365;11.116805555555564;11.628226744186048;11.178072625698324
04:18:41 INFO - PID 17435 | __end_tp_report
04:18:41 INFO - PID 17435 | __start_cc_report
04:18:41 INFO - PID 17435 | _x_x_mozilla_cycle_collect,597
04:18:41 INFO - PID 17435 | __end_cc_report
04:18:41 INFO - PID 17435 | __startTimestamp1509275921778__endTimestamp
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | ------- Summary: start -------
04:18:41 INFO - PID 17435 | Number of tests: 18
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.69 Median:1.69 stddev:0.01 (0.3%) stddev-sans-first:0.01
04:18:41 INFO - PID 17435 | 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
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.3%) stddev-sans-first:0.00
04:18:41 INFO - PID 17435 | 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
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.80 Median:1.81 stddev:0.02 (0.9%) stddev-sans-first:0.02
04:18:41 INFO - PID 17435 | Values: 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.77 Median:1.78 stddev:0.02 (1.2%) stddev-sans-first:0.02
04:18:41 INFO - PID 17435 | Values: 1.7 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.16 Median:4.20 stddev:0.14 (3.4%) stddev-sans-first:0.15
04:18:41 INFO - PID 17435 | Values: 4.1 4.0 4.1 4.2 4.3 4.3 4.2 4.3 4.3 4.2 4.1 3.8
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.14 Median:4.16 stddev:0.09 (2.0%) stddev-sans-first:0.08
04:18:41 INFO - PID 17435 | Values: 4.2 4.1 4.1 4.2 4.3 4.2 4.1 4.1 4.2 4.2 4.0 4.0
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.63 Median:3.63 stddev:0.05 (1.3%) stddev-sans-first:0.05
04:18:41 INFO - PID 17435 | Values: 3.6 3.7 3.6 3.6 3.6 3.6 3.6 3.7 3.7 3.6 3.6 3.7
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.54 Median:3.57 stddev:0.05 (1.4%) stddev-sans-first:0.05
04:18:41 INFO - PID 17435 | Values: 3.6 3.6 3.5 3.6 3.5 3.5 3.6 3.6 3.6 3.5 3.5 3.6
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.72 Median:5.76 stddev:0.17 (3.0%) stddev-sans-first:0.12
04:18:41 INFO - PID 17435 | Values: 5.3 5.9 5.7 5.6 5.8 5.7 5.6 5.8 5.8 6.0 5.6 5.8
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.65 Median:5.70 stddev:0.14 (2.5%) stddev-sans-first:0.15
04:18:41 INFO - PID 17435 | Values: 5.7 5.7 5.6 5.7 5.7 5.5 5.3 5.7 5.7 5.9 5.7 5.6
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.91 Median:9.77 stddev:0.23 (2.4%) stddev-sans-first:0.24
04:18:41 INFO - PID 17435 | Values: 10.0 9.7 9.8 9.9 10.2 10.1 9.3 9.8 10.2 10.0 10.0 9.8
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.75 Median:9.08 stddev:0.44 (4.8%) stddev-sans-first:0.36
04:18:41 INFO - PID 17435 | Values: 8.9 9.6 10.1 10.2 10.3 10.0 9.5 9.6 9.3 10.0 10.2 9.4
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.62 Median:11.63 stddev:0.10 (0.8%) stddev-sans-first:0.09
04:18:41 INFO - PID 17435 | Values: 11.8 11.5 11.6 11.6 11.7 11.5 11.7 11.6 11.5 11.8 11.6 11.5
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:11.09 Median:11.11 stddev:0.14 (1.2%) stddev-sans-first:0.14
04:18:41 INFO - PID 17435 | Values: 11.0 11.1 10.9 11.1 11.2 11.1 11.3 11.1 10.9 11.1 11.3 11.0
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.62 Median:12.58 stddev:0.21 (1.7%) stddev-sans-first:0.21
04:18:41 INFO - PID 17435 | Values: 12.4 12.5 12.4 12.6 12.7 12.6 12.9 12.5 12.6 12.8 13.0 12.6
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.02 Median:12.02 stddev:0.17 (1.5%) stddev-sans-first:0.18
04:18:41 INFO - PID 17435 | Values: 11.9 12.0 12.0 12.1 11.9 12.2 12.1 12.0 12.4 12.1 11.8 11.8
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.73 Median:11.77 stddev:0.10 (0.8%) stddev-sans-first:0.07
04:18:41 INFO - PID 17435 | Values: 11.5 11.8 11.6 11.8 11.8 11.7 11.7 11.8 11.7 11.7 11.8 11.8
04:18:41 INFO - PID 17435 |
04:18:41 INFO - PID 17435 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.25 Median:11.27 stddev:0.15 (1.3%) stddev-sans-first:0.16
04:18:41 INFO - PID 17435 | Values: 11.2 11.3 11.1 11.3 11.2 11.1 11.3 11.1 11.4 11.1 11.6 11.2
04:18:41 INFO - PID 17435 | -------- Summary: end --------
04:18:41 INFO - PID 17435 |
04:18:42 INFO - TEST-INFO | 17435: exit 0
04:18:42 INFO - TEST-OK | basic_compositor_video | took 562298ms
04:18:42 INFO - TEST-START | glvideo
04:18:42 INFO - Initialising browser for glvideo test...
04:18:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:40234/getInfo.html -profile /tmp/tmpy_b7oO/profile
04:18:42 INFO - TEST-INFO | started process 18737 (/builds/slave/test/build/application/firefox/firefox http://localhost:40234/getInfo.html)
04:18:48 INFO - TEST-INFO | 18737: exit 0
04:18:48 INFO - Browser initialized.
04:18:48 INFO - Running cycle 1/1 for glvideo test...
04:18:48 INFO - Using env: {'DISPLAY': ':0',
04:18:48 INFO - 'HOME': '/home/cltbld',
04:18:48 INFO - 'JSGC_DISABLE_POISONING': '1',
04:18:48 INFO - 'LANG': 'en_US.UTF-8',
04:18:48 INFO - 'LANGUAGE': 'en_US:en',
04:18:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
04:18:48 INFO - 'LOGNAME': 'cltbld',
04:18:48 INFO - 'MAIL': '/var/mail/cltbld',
04:18:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:18:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
04:18:48 INFO - 'MOZ_CRASHREPORTER': '1',
04:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:18:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
04:18:48 INFO - 'MOZ_NO_REMOTE': '1',
04:18:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:18:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:18:48 INFO - 'NO_EM_RESTART': '1',
04:18:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:18:48 INFO - 'PWD': '/builds/slave/test',
04:18:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:18:48 INFO - 'RUST_BACKTRACE': 'full',
04:18:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
04:18:48 INFO - 'SHELL': '/bin/bash',
04:18:48 INFO - 'SHLVL': '1',
04:18:48 INFO - 'STYLO_FORCE_DISABLED': '1',
04:18:48 INFO - 'TERM': 'linux',
04:18:48 INFO - 'TMOUT': '86400',
04:18:48 INFO - 'USER': 'cltbld',
04:18:48 INFO - 'XDG_SESSION_COOKIE': '1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270',
04:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:18:48 INFO - '_': '/tools/buildbot/bin/python'}
04:18:48 INFO - TEST-INFO | started process 19008 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy_b7oO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
04:18:49 INFO - PID 19008 |
04:18:49 INFO - PID 19008 | (/builds/slave/test/build/application/firefox/firefox:19059): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
04:18:49 INFO - PID 19008 |
04:18:51 INFO - PID 19008 | [talos glvideo result] Mean tick time across 100 ticks: 7.687950000000001 ms
04:18:51 INFO - PID 19008 | Cycle 1(1): loaded http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html)
04:18:52 INFO - PID 19008 | [talos glvideo result] Mean tick time across 100 ticks: 6.545300000000001 ms
04:18:52 INFO - PID 19008 | Cycle 1(2): loaded http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html)
04:18:53 INFO - PID 19008 | [talos glvideo result] Mean tick time across 100 ticks: 6.56485 ms
04:18:53 INFO - PID 19008 | Cycle 1(3): loaded http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html)
04:18:55 INFO - PID 19008 | [talos glvideo result] Mean tick time across 100 ticks: 6.56465 ms
04:18:55 INFO - PID 19008 | Cycle 1(4): loaded http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html)
04:18:56 INFO - PID 19008 | [talos glvideo result] Mean tick time across 100 ticks: 6.50875 ms
04:18:56 INFO - PID 19008 | Cycle 1(5): loaded http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:40234/tests/webgl/benchmarks/video/video_upload.html)
04:18:56 INFO - PID 19008 | __start_tp_report
04:18:56 INFO - PID 19008 | _x_x_mozilla_page_load
04:18:56 INFO - PID 19008 | _x_x_mozilla_page_load_details
04:18:56 INFO - PID 19008 | |i|pagename|runs|
04:18:56 INFO - PID 19008 | |0;Mean tick time across 100 ticks: ;7.687950000000001;6.545300000000001;6.56485;6.56465;6.50875
04:18:56 INFO - PID 19008 | __end_tp_report
04:18:56 INFO - PID 19008 | __start_cc_report
04:18:56 INFO - PID 19008 | _x_x_mozilla_cycle_collect,278
04:18:56 INFO - PID 19008 | __end_cc_report
04:18:56 INFO - PID 19008 | __startTimestamp1509275936846__endTimestamp
04:18:56 INFO - PID 19008 |
04:18:56 INFO - PID 19008 | ------- Summary: start -------
04:18:56 INFO - PID 19008 | Number of tests: 1
04:18:56 INFO - PID 19008 |
04:18:56 INFO - PID 19008 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.77 Median:6.56 stddev:0.51 (7.8%) stddev-sans-first:0.03
04:18:56 INFO - PID 19008 | Values: 7.7 6.5 6.6 6.6 6.5
04:18:56 INFO - PID 19008 | -------- Summary: end --------
04:18:56 INFO - PID 19008 |
04:18:57 INFO - PID 19008 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
04:18:57 INFO - TEST-INFO | 19008: exit 0
04:18:57 INFO - TEST-OK | glvideo | took 15107ms
04:18:57 INFO - SUITE-END | took 577s
04:18:57 INFO - Completed test suite (00:09:37)
04:18:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.683356939717395, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.689552364864865, 1.695310734463277, 1.6849859629421673, 1.6827902411665732, 1.6902845070422536, 1.6830089736399325, 1.6846996069623807, 1.6802603583426652, 1.6867903316469928, 1.6791158365976497, 1.6949689265536725, 1.683008973639933], "value": 1.6846996069623807, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6684778982485409, 1.6711319966583122, 1.6687114261884908, 1.6752973199329981, 1.6755946398659964, 1.6740711297071131, 1.6836237373737377, 1.6717794486215538, 1.6812647058823529, 1.6676374999999999, 1.6737112970711296, 1.6771877619446771], "value": 1.6740711297071131, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.782694592988711, 1.8045460012026457, 1.7957749850388987, 1.8316697191697189, 1.7883402860548272, 1.7922550776583033, 1.8321764346764344, 1.8079096385542168, 1.7987350119904078, 1.8133323262839878, 1.8086558167570828, 1.7906533412887828], "value": 1.8045460012026457, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7347441457068515, 1.748461538461539, 1.750389326334208, 1.7844424620874224, 1.7876050044682752, 1.7756122448979599, 1.7798131672597859, 1.7819011576135357, 1.7909444941808421, 1.7591468777484605, 1.8137579329102453, 1.7637433862433862], "value": 1.7798131672597859, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.121531593406594, 4.038499327052489, 4.12770976616231, 4.214543539325844, 4.323270893371759, 4.273874643874644, 4.178962395543176, 4.256723404255319, 4.329300144300144, 4.1500276625172905, 4.070658073270015, 3.812369758576873], "value": 4.178962395543176, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.221529535864978, 4.0988422131147555, 4.057494929006084, 4.150674273858921, 4.282880085653104, 4.202237394957982, 4.115905349794239, 4.099969262295081, 4.2122210526315795, 4.168874999999999, 4.026106639839031, 4.002060000000001], "value": 4.115905349794239, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.6156746987951833, 3.6507785888077886, 3.614885542168674, 3.6238768115942004, 3.610180505415162, 3.5885705741626817, 3.645388821385177, 3.7085414091470965, 3.6814478527607375, 3.5846236559139806, 3.567241379310346, 3.709239802224971], "value": 3.6238768115942004, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.553126110124334, 3.5846146953404956, 3.509254385964915, 3.6103429602888104, 3.491404886561956, 3.491998254799297, 3.572410714285713, 3.590897666068218, 3.5781395348837215, 3.5090701754385933, 3.4620761245674707, 3.571553571428571], "value": 3.571553571428571, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.292918871252209, 5.884098039215685, 5.703707224334604, 5.609336448598129, 5.7701057692307725, 5.737906309751432, 5.61874531835206, 5.826699029126214, 5.83772373540856, 5.965636182902585, 5.619597378277157, 5.758675623800385], "value": 5.758675623800385, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.747399425287349, 5.731117478510028, 5.603501400560226, 5.748778735632186, 5.700000000000002, 5.5248480662983495, 5.291891534391541, 5.651878531073445, 5.6997863247863245, 5.8670087976539635, 5.666713881019826, 5.6191853932584275], "value": 5.666713881019826, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.9693853820598, 9.74366883116883, 9.804313725490193, 9.871381578947373, 10.17138983050848, 10.103316498316502, 9.319937888198758, 9.836967213114747, 10.172355932203393, 10.00031666666667, 10.03613712374582, 9.839688524590162], "value": 9.871381578947373, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8.889266666666662, 9.574066985645931, 10.051507537688442, 10.20806122448979, 10.256435897435903, 10.003474999999998, 9.484028436018962, 9.570693779904314, 9.263217592592591, 10.000299999999989, 10.205867346938769, 9.43474056603774], "value": 10.000299999999989, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.765431372549024, 11.494712643678158, 11.632248062015513, 11.585019305019307, 11.674182879377444, 11.54303846153847, 11.720957031249995, 11.630038759689919, 11.497413793103437, 11.769392156862729, 11.58368725868727, 11.539057692307702], "value": 11.585019305019307, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.994120879120882, 11.116500000000006, 10.93245901639344, 11.05110497237569, 11.236713483146039, 11.053259668508284, 11.30248587570622, 11.117638888888905, 10.87230978260872, 11.113250000000011, 11.306045197740103, 10.993379120879112], "value": 11.113250000000011, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.398533057851239, 12.451431535269732, 12.398842975206628, 12.55656903765689, 12.662742616033754, 12.553138075313825, 12.931724137931019, 12.45128630705395, 12.608109243697493, 12.820619658119664, 13.047956521739126, 12.556631799163164], "value": 12.556631799163164, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.911369047619008, 11.978353293413143, 11.977065868263463, 12.053463855421686, 11.90755952380953, 12.19771341463412, 12.127272727272727, 11.97700598802393, 12.423757763975146, 12.05421686746988, 11.836449704142016, 11.766705882352964], "value": 11.978353293413143, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.49754789272031, 11.768627450980393, 11.584247104247103, 11.767686274509812, 11.813385826771647, 11.721210937500018, 11.67496108949415, 11.812539370078719, 11.718925781249993, 11.720078125000015, 11.812342519685036, 11.812559055118108], "value": 11.767686274509812, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.176899441340787, 11.303248587570637, 11.114749999999994, 11.301045197740091, 11.236601123595495, 11.116222222222213, 11.302768361581942, 11.11522222222225, 11.363948863636365, 11.116805555555564, 11.628226744186048, 11.178072625698324], "value": 11.236601123595495, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.687950000000001, 6.545300000000001, 6.56485, 6.56465, 6.50875], "value": 6.554975000000001, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
04:18:57 INFO - Return code: 0
04:18:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
04:18:57 INFO - # TBPL SUCCESS #
04:18:57 INFO - Running post-action listener: _package_coverage_data
04:18:57 INFO - Running post-action listener: _resource_record_post_action
04:18:57 INFO - [mozharness: 2017-10-29 11:18:57.987885Z] Finished run-tests step (success)
04:18:57 INFO - Running post-run listener: _resource_record_post_run
04:18:58 INFO - Total resource usage - Wall time: 597s; CPU: 39.0%; Read bytes: 6275072; Write bytes: 446529536; Read time: 280; Write time: 313752
04:18:58 INFO - TinderboxPrint: CPU usage
38.6%
04:18:58 INFO - TinderboxPrint: I/O read bytes / time
6,275,072 / 280
04:18:58 INFO - TinderboxPrint: I/O write bytes / time
446,529,536 / 313,752
04:18:58 INFO - TinderboxPrint: CPU idle
2,875.1 (61.4%)
04:18:58 INFO - TinderboxPrint: CPU system
305.3 (6.5%)
04:18:58 INFO - TinderboxPrint: CPU user
1,489.2 (31.8%)
04:18:58 INFO - TinderboxPrint: Swap in / out
0 / 0
04:18:58 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 130224128; Read time: 0; Write time: 118156
04:18:58 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
04:18:58 INFO - run-tests - Wall time: 578s; CPU: 39.0%; Read bytes: 6275072; Write bytes: 316305408; Read time: 280; Write time: 195596
04:18:58 INFO - Running post-run listener: _upload_blobber_files
04:18:58 INFO - Blob upload gear active.
04:18:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
04:18:58 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
04:18:58 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
04:18:58 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
04:18:58 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
04:18:58 INFO - SNIMissingWarning
04:18:58 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
04:18:58 INFO - InsecurePlatformWarning
04:18:58 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.)
04:18:58 INFO - SubjectAltNameWarning
04:18:58 INFO - (blobuploader) - INFO - Open directory for files ...
04:18:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
04:18:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:18:58 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:18:58 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
04:18:58 INFO - InsecurePlatformWarning
04:18:58 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.)
04:18:58 INFO - SubjectAltNameWarning
04:18:59 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
04:18:59 INFO - InsecurePlatformWarning
04:18:59 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
04:18:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:18:59 INFO - (blobuploader) - INFO - Done attempting.
04:18:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
04:18:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:18:59 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:18:59 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
04:18:59 INFO - InsecurePlatformWarning
04:18:59 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.)
04:18:59 INFO - SubjectAltNameWarning
04:19:00 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
04:19:00 INFO - InsecurePlatformWarning
04:19:00 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
04:19:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:19:00 INFO - (blobuploader) - INFO - Done attempting.
04:19:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
04:19:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:19:00 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:19:00 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
04:19:00 INFO - InsecurePlatformWarning
04:19:01 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.)
04:19:01 INFO - SubjectAltNameWarning
04:19:01 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
04:19:01 INFO - InsecurePlatformWarning
04:19:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
04:19:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:19:01 INFO - (blobuploader) - INFO - Done attempting.
04:19:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
04:19:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:19:01 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:19:01 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
04:19:01 INFO - InsecurePlatformWarning
04:19:02 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.)
04:19:02 INFO - SubjectAltNameWarning
04:19:04 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
04:19:04 INFO - InsecurePlatformWarning
04:19:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
04:19:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:19:04 INFO - (blobuploader) - INFO - Done attempting.
04:19:04 INFO - (blobuploader) - INFO - Iteration through files over.
04:19:04 INFO - Return code: 0
04:19:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
04:19:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
04:19:04 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fc8cae99c8c5256ea286473cc8c2f1c4c0a86d2c181a66970502c77692be6a318f283092e6a24a56f6c64fe1f3e9153ba80332c32354e48387060c406643057", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bc514032d5ac2a18f6ccec207c6eea3cb2e21c5422302a2093a80eb4ea380d22f2fa098cbd01fd0968a8a616c5cf3bcf9b948dd90f99dab3ce25a3b20d3bc7e", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b5d87e5016f5d7e7611bc2bd44023a830fb7c42603b1d9367448b684ec948a1e712b8ed8975b67ecc51b44edc68d6a79d373cf1342a159487b05400eb2948796", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ef0c2a046ebdb03a69ad2ef827f7c9529954bdf3e768d8f3ba41f52e3515d0bdfc042d1eb23209e7966f7954a13c435b26e5a94fe9914811579750e5edbc48f"}
04:19:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
04:19:04 INFO - Writing to file /builds/slave/test/properties/blobber_files
04:19:04 INFO - Contents:
04:19:04 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fc8cae99c8c5256ea286473cc8c2f1c4c0a86d2c181a66970502c77692be6a318f283092e6a24a56f6c64fe1f3e9153ba80332c32354e48387060c406643057", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bc514032d5ac2a18f6ccec207c6eea3cb2e21c5422302a2093a80eb4ea380d22f2fa098cbd01fd0968a8a616c5cf3bcf9b948dd90f99dab3ce25a3b20d3bc7e", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b5d87e5016f5d7e7611bc2bd44023a830fb7c42603b1d9367448b684ec948a1e712b8ed8975b67ecc51b44edc68d6a79d373cf1342a159487b05400eb2948796", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ef0c2a046ebdb03a69ad2ef827f7c9529954bdf3e768d8f3ba41f52e3515d0bdfc042d1eb23209e7966f7954a13c435b26e5a94fe9914811579750e5edbc48f"}
04:19:04 INFO - Running post-run listener: copy_logs_to_upload_dir
04:19:04 INFO - Copying logs to upload dir...
04:19:04 INFO - mkdir: /builds/slave/test/build/upload/logs
04:19:04 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=656.969136
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 57 secs) (at 2017-10-29 04:19:04.482532) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-29 04:19:04.486266) =========
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=1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fc8cae99c8c5256ea286473cc8c2f1c4c0a86d2c181a66970502c77692be6a318f283092e6a24a56f6c64fe1f3e9153ba80332c32354e48387060c406643057", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bc514032d5ac2a18f6ccec207c6eea3cb2e21c5422302a2093a80eb4ea380d22f2fa098cbd01fd0968a8a616c5cf3bcf9b948dd90f99dab3ce25a3b20d3bc7e", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b5d87e5016f5d7e7611bc2bd44023a830fb7c42603b1d9367448b684ec948a1e712b8ed8975b67ecc51b44edc68d6a79d373cf1342a159487b05400eb2948796", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ef0c2a046ebdb03a69ad2ef827f7c9529954bdf3e768d8f3ba41f52e3515d0bdfc042d1eb23209e7966f7954a13c435b26e5a94fe9914811579750e5edbc48f"}
build_url:https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011317
build_url: 'https://queue.taskcluster.net/v1/task/VWQYlIZWQT-XQiNkj0GI2g/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4fc8cae99c8c5256ea286473cc8c2f1c4c0a86d2c181a66970502c77692be6a318f283092e6a24a56f6c64fe1f3e9153ba80332c32354e48387060c406643057", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5bc514032d5ac2a18f6ccec207c6eea3cb2e21c5422302a2093a80eb4ea380d22f2fa098cbd01fd0968a8a616c5cf3bcf9b948dd90f99dab3ce25a3b20d3bc7e", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/b5d87e5016f5d7e7611bc2bd44023a830fb7c42603b1d9367448b684ec948a1e712b8ed8975b67ecc51b44edc68d6a79d373cf1342a159487b05400eb2948796", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3ef0c2a046ebdb03a69ad2ef827f7c9529954bdf3e768d8f3ba41f52e3515d0bdfc042d1eb23209e7966f7954a13c435b26e5a94fe9914811579750e5edbc48f"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-29 04:19:04.515701) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-29 04:19:04.516043) =========
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=1258088b6da462a47ba2cd90000001a2-1509275284.722446-1899303270
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.005120
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-29 04:19:04.601376) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-29 04:19:04.601707) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-29 04:19:04.602077) =========
========= Total master_lag: 0.20 =========