builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s-pgo
slave: talos-linux64-ix-056
starttime: 1508934749.06
results: success (0)
revision: 6e5e01b3f15f8d995707875d8d2243f9dae52f08
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.064559) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.064988) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.065270) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.106143) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.106465) =========
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=52faae308a556c440401f8e5000001e8-1508934253.220159-468482307
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-25 05:32:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 41.9M=0s
2017-10-25 05:32:29 (41.9 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.218448
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.382874) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.383181) =========
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=52faae308a556c440401f8e5000001e8-1508934253.220159-468482307
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.018577
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:29.433271) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-10-25 05:32:29.433599) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6e5e01b3f15f8d995707875d8d2243f9dae52f08 --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 6e5e01b3f15f8d995707875d8d2243f9dae52f08 --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=52faae308a556c440401f8e5000001e8-1508934253.220159-468482307
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-25 05:32:29,482 truncating revision to first 12 chars
2017-10-25 05:32:29,482 Setting DEBUG logging.
2017-10-25 05:32:29,482 attempt 1/10
2017-10-25 05:32:29,482 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6e5e01b3f15f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-25 05:32:30,200 unpacking tar archive at: mozilla-central-6e5e01b3f15f/testing/mozharness/
program finished with exit code 0
elapsedTime=2.134936
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-10-25 05:32:31.586138) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:31.586489) =========
script_repo_revision: 6e5e01b3f15f8d995707875d8d2243f9dae52f08
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:31.586849) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:31.587112) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-25 05:32:31.601898) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 4 secs) (at 2017-10-25 05:32:31.602264) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1508934253.220159-468482307
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
05:32:31 INFO - MultiFileLogger online at 20171025 05:32:31 in /builds/slave/test
05:32:31 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
05:32:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
05:32:31 INFO - {'append_to_log': False,
05:32:31 INFO - 'base_work_dir': '/builds/slave/test',
05:32:31 INFO - 'blob_upload_branch': 'Firefox',
05:32:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
05:32:31 INFO - 'branch': 'Firefox',
05:32:31 INFO - 'buildbot_json_path': 'buildprops.json',
05:32:31 INFO - 'code_coverage': False,
05:32:31 INFO - 'config_files': ('talos/linux_config.py',),
05:32:31 INFO - 'default_actions': ('clobber',
05:32:31 INFO - 'read-buildbot-config',
05:32:31 INFO - 'download-and-extract',
05:32:31 INFO - 'populate-webroot',
05:32:31 INFO - 'create-virtualenv',
05:32:31 INFO - 'install',
05:32:31 INFO - 'setup-mitmproxy',
05:32:31 INFO - 'run-tests'),
05:32:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
05:32:31 INFO - 'disable_ccov_upload': False,
05:32:31 INFO - 'disable_stylo': False,
05:32:31 INFO - 'download_minidump_stackwalk': True,
05:32:31 INFO - 'download_symbols': 'ondemand',
05:32:31 INFO - 'enable_stylo': False,
05:32:31 INFO - 'enable_webrender': False,
05:32:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
05:32:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
05:32:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
05:32:31 INFO - 'gecko_profile': False,
05:32:31 INFO - 'gecko_profile_interval': 0,
05:32:31 INFO - 'installer_path': 'installer.exe',
05:32:31 INFO - 'log_level': 'info',
05:32:31 INFO - 'log_name': 'talos',
05:32:31 INFO - 'log_to_console': True,
05:32:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
05:32:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
05:32:31 INFO - 'opt_config_files': (),
05:32:31 INFO - 'pip_index': False,
05:32:31 INFO - 'suite': 'g4-stylo-disabled-e10s',
05:32:31 INFO - 'system_bits': '32',
05:32:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
05:32:31 INFO - 'title': 'talos-linux64-ix-056',
05:32:31 INFO - 'tooltool_cache': '/builds/tooltool_cache',
05:32:31 INFO - 'use_talos_json': True,
05:32:31 INFO - 'verify': 'False',
05:32:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
05:32:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
05:32:31 INFO - 'work_dir': 'build'}
05:32:31 INFO - [mozharness: 2017-10-25 12:32:31.897725Z] Running clobber step.
05:32:31 INFO - Running pre-action listener: _resource_record_pre_action
05:32:31 INFO - Running main action method: clobber
05:32:31 INFO - rmtree: /builds/slave/test/build
05:32:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
05:32:32 INFO - Running post-action listener: _resource_record_post_action
05:32:32 INFO - [mozharness: 2017-10-25 12:32:32.912213Z] Finished clobber step (success)
05:32:32 INFO - [mozharness: 2017-10-25 12:32:32.912308Z] Running read-buildbot-config step.
05:32:32 INFO - Running pre-action listener: _resource_record_pre_action
05:32:32 INFO - Running main action method: read_buildbot_config
05:32:32 INFO - Using buildbot properties:
05:32:32 INFO - {
05:32:32 INFO - "project": "",
05:32:32 INFO - "product": "firefox",
05:32:32 INFO - "who": "archaeopteryx@coole-files.de",
05:32:32 INFO - "installer_path": "public/build/target.tar.bz2",
05:32:32 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
05:32:32 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
05:32:32 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-stylo-disabled-e10s",
05:32:32 INFO - "stage_platform": "linux64-pgo",
05:32:32 INFO - "basedir": "/builds/slave/test",
05:32:32 INFO - "buildnumber": 96,
05:32:32 INFO - "platform": "ubuntu64_hw",
05:32:32 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
05:32:32 INFO - "slavebuilddir": "test",
05:32:32 INFO - "taskId": "YyzVQxogSQ2oNEFDTV1q9A",
05:32:32 INFO - "branch": "mozilla-central",
05:32:32 INFO - "script_repo_revision": "production",
05:32:32 INFO - "revision": "6e5e01b3f15f8d995707875d8d2243f9dae52f08",
05:32:32 INFO - "slavename": "talos-linux64-ix-056",
05:32:32 INFO - "repo_path": "mozilla-central"
05:32:32 INFO - }
05:32:32 INFO - Finding installer, test and symbols from parent task.
05:32:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YyzVQxogSQ2oNEFDTV1q9A'}, attempt #1
05:32:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YyzVQxogSQ2oNEFDTV1q9A'}, attempt #1
05:32:33 INFO - Task dependencies: IObgXzdYS12iK8A9zQpPWQ
05:32:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ'}, attempt #1
05:32:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2
05:32:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.test_packages.json
05:32:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.crashreporter-symbols.zip
05:32:34 INFO - Running post-action listener: _resource_record_post_action
05:32:34 INFO - [mozharness: 2017-10-25 12:32:34.328897Z] Finished read-buildbot-config step (success)
05:32:34 INFO - [mozharness: 2017-10-25 12:32:34.329256Z] Running download-and-extract step.
05:32:34 INFO - Running pre-action listener: _resource_record_pre_action
05:32:34 INFO - Running main action method: download_and_extract
05:32:34 INFO - mkdir: /builds/slave/test/build/tests
05:32: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')]}
05:32:34 INFO - trying https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.test_packages.json
05:32:34 INFO - Downloading https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
05:32:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
05:32:35 INFO - Downloaded 989 bytes.
05:32:35 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
05:32:35 INFO - Using the following test package requirements:
05:32:35 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
05:32:35 INFO - u'common': [u'target.common.tests.zip'],
05:32:35 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
05:32:35 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
05:32:35 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
05:32:35 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
05:32:35 INFO - u'mozbase': [u'target.common.tests.zip'],
05:32:35 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
05:32:35 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
05:32:35 INFO - u'web-platform': [u'target.common.tests.zip',
05:32:35 INFO - u'target.web-platform.tests.tar.gz'],
05:32:35 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
05:32:35 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
05:32:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.common.tests.zip
05:32:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
05:32:35 INFO - Fetch https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.common.tests.zip into memory
05:32:37 INFO - Content-Length response header: 56605285
05:32:37 INFO - Bytes received: 56605285
05:32:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.talos.tests.zip
05:32:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
05:32:48 INFO - Fetch https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.talos.tests.zip into memory
05:32:49 INFO - Content-Length response header: 18737999
05:32:49 INFO - Bytes received: 18737999
05:32:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:32:49 INFO - trying https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2
05:32:49 INFO - Downloading https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
05:32:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
05:32:51 INFO - Downloaded 63485350 bytes.
05:32:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2
05:32:52 INFO - mkdir: /builds/slave/test/properties
05:32:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
05:32:52 INFO - Writing to file /builds/slave/test/properties/build_url
05:32:52 INFO - Contents:
05:32:52 INFO - build_url:https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2
05:32:52 INFO - Running post-action listener: _resource_record_post_action
05:32:52 INFO - Running post-action listener: find_tests_for_verification
05:32:52 INFO - Running post-action listener: set_extra_try_arguments
05:32:52 INFO - [mozharness: 2017-10-25 12:32:52.665700Z] Finished download-and-extract step (success)
05:32:52 INFO - [mozharness: 2017-10-25 12:32:52.665874Z] Running populate-webroot step.
05:32:52 INFO - Running pre-action listener: _resource_record_pre_action
05:32:52 INFO - Running main action method: populate_webroot
05:32:52 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
05:32:52 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['tresize',
05:32:52 INFO - 'tcanvasmark']},
05:32:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
05:32:52 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['dromaeo_css',
05:32:52 INFO - 'kraken']},
05:32:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
05:32:52 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
05:32:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'tests': ['damp', 'tps']},
05:32:52 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['damp', 'tps']},
05:32:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
05:32:52 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['dromaeo_dom']},
05:32:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
05:32:52 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['basic_compositor_video',
05:32:52 INFO - 'glvideo']},
05:32:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
05:32:52 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['ts_paint_webext',
05:32:52 INFO - 'tp5o_webext']},
05:32:52 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
05:32:52 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
05:32:52 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
05:32:52 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
05:32:52 INFO - 'talos_options': ['--mitmproxy',
05:32:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
05:32:52 INFO - '--firstNonBlankPaint'],
05:32:52 INFO - 'tests': ['tp6_google_heavy',
05:32:52 INFO - 'tp6_youtube_heavy',
05:32:52 INFO - 'tp6_amazon_heavy',
05:32:52 INFO - 'tp6_facebook_heavy']},
05:32:52 INFO - 'other-e10s': {'tests': ['a11yr',
05:32:52 INFO - 'ts_paint',
05:32:52 INFO - 'tpaint',
05:32:52 INFO - 'sessionrestore',
05:32:52 INFO - 'sessionrestore_many_windows',
05:32:52 INFO - 'sessionrestore_no_auto_restore',
05:32:52 INFO - 'tabpaint',
05:32:52 INFO - 'cpstartup']},
05:32:52 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['a11yr',
05:32:52 INFO - 'ts_paint',
05:32:52 INFO - 'tpaint',
05:32:52 INFO - 'sessionrestore',
05:32:52 INFO - 'sessionrestore_many_windows',
05:32:52 INFO - 'sessionrestore_no_auto_restore',
05:32:52 INFO - 'tabpaint',
05:32:52 INFO - 'cpstartup']},
05:32:52 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
05:32:52 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
05:32:52 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['perf_reftest_singletons']},
05:32:52 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['perf_reftest']},
05:32:52 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
05:32:52 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['speedometer']},
05:32:52 INFO - 'svgr-e10s': {'tests': ['tsvgx',
05:32:52 INFO - 'tsvgr_opacity',
05:32:52 INFO - 'tart',
05:32:52 INFO - 'tscrollx',
05:32:52 INFO - 'tsvg_static']},
05:32:52 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['tsvgx',
05:32:52 INFO - 'tsvgr_opacity',
05:32:52 INFO - 'tart',
05:32:52 INFO - 'tscrollx',
05:32:52 INFO - 'tsvg_static']},
05:32:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
05:32:52 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'talos_options': ['--disable-stylo'],
05:32:52 INFO - 'tests': ['tp5o']},
05:32:52 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
05:32:52 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
05:32:52 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
05:32:52 INFO - 'talos_options': ['--mitmproxy',
05:32:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
05:32:52 INFO - '--firstNonBlankPaint'],
05:32:52 INFO - 'tests': ['tp6_google',
05:32:52 INFO - 'tp6_youtube',
05:32:52 INFO - 'tp6_amazon',
05:32:52 INFO - 'tp6_facebook']},
05:32:52 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
05:32:52 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
05:32:52 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
05:32:52 INFO - 'talos_options': ['--disable-stylo',
05:32:52 INFO - '--mitmproxy',
05:32:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
05:32:52 INFO - '--firstNonBlankPaint'],
05:32:52 INFO - 'tests': ['tp6_google',
05:32:52 INFO - 'tp6_youtube',
05:32:52 INFO - 'tp6_amazon',
05:32:52 INFO - 'tp6_facebook']},
05:32:52 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
05:32:52 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
05:32:52 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
05:32:52 INFO - 'talos_options': ['--stylo-threads=1',
05:32:52 INFO - '--mitmproxy',
05:32:52 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
05:32:52 INFO - '--firstNonBlankPaint'],
05:32:52 INFO - 'tests': ['tp6_google',
05:32:52 INFO - 'tp6_youtube',
05:32:52 INFO - 'tp6_amazon',
05:32:52 INFO - 'tp6_facebook']},
05:32:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'talos_options': ['--xperf_path',
05:32:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
05:32:52 INFO - 'tests': ['tp5n']},
05:32:52 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
05:32:52 INFO - 'talos_options': ['--disable-stylo',
05:32:52 INFO - '--xperf_path',
05:32:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
05:32:52 INFO - 'tests': ['tp5n']}}}
05:32:52 INFO - Running post-action listener: _resource_record_post_action
05:32:52 INFO - [mozharness: 2017-10-25 12:32:52.690550Z] Finished populate-webroot step (success)
05:32:52 INFO - [mozharness: 2017-10-25 12:32:52.690637Z] Running create-virtualenv step.
05:32:52 INFO - Running pre-action listener: _resource_record_pre_action
05:32:52 INFO - Running main action method: create_virtualenv
05:32:52 INFO - Creating virtualenv /builds/slave/test/build/venv
05:32:52 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
05:32:52 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
05:32:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
05:32:55 INFO - Using real prefix '/usr'
05:32:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python
05:32:55 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
05:32:57 INFO - Installing setuptools, pip, wheel...done.
05:32:57 INFO - Return code: 0
05:32:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
05:32: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')]}
05:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:32: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')]}
05:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:32:57 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 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:32:57 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
05:32:57 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
05:32:57 INFO - Using env: {'DISPLAY': ':0',
05:32:57 INFO - 'HOME': '/home/cltbld',
05:32:57 INFO - 'LANG': 'en_US.UTF-8',
05:32:57 INFO - 'LANGUAGE': 'en_US:en',
05:32:57 INFO - 'LOGNAME': 'cltbld',
05:32:57 INFO - 'MAIL': '/var/mail/cltbld',
05:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:32:57 INFO - 'MOZ_NO_REMOTE': '1',
05:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:32:57 INFO - 'NO_EM_RESTART': '1',
05:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:32:57 INFO - 'PWD': '/builds/slave/test',
05:32:57 INFO - 'SHELL': '/bin/bash',
05:32:57 INFO - 'SHLVL': '1',
05:32:57 INFO - 'TERM': 'linux',
05:32:57 INFO - 'TMOUT': '86400',
05:32:57 INFO - 'USER': 'cltbld',
05:32:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:32:57 INFO - '_': '/tools/buildbot/bin/python'}
05:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple
05:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
05:32:59 INFO - Return code: 0
05:32:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
05:32:59 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')]}
05:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:32:59 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')]}
05:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:32:59 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 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:32:59 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
05:32:59 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
05:32:59 INFO - Using env: {'DISPLAY': ':0',
05:32:59 INFO - 'HOME': '/home/cltbld',
05:32:59 INFO - 'LANG': 'en_US.UTF-8',
05:32:59 INFO - 'LANGUAGE': 'en_US:en',
05:32:59 INFO - 'LOGNAME': 'cltbld',
05:32:59 INFO - 'MAIL': '/var/mail/cltbld',
05:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:32:59 INFO - 'MOZ_NO_REMOTE': '1',
05:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:32:59 INFO - 'NO_EM_RESTART': '1',
05:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:32:59 INFO - 'PWD': '/builds/slave/test',
05:32:59 INFO - 'SHELL': '/bin/bash',
05:32:59 INFO - 'SHLVL': '1',
05:32:59 INFO - 'TERM': 'linux',
05:32:59 INFO - 'TMOUT': '86400',
05:32:59 INFO - 'USER': 'cltbld',
05:32:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:32:59 INFO - '_': '/tools/buildbot/bin/python'}
05:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple
05:32:59 INFO - Collecting psutil>=3.1.1
05:33:00 INFO - Installing collected packages: psutil
05:33:00 INFO - Successfully installed psutil-3.1.1
05:33:00 INFO - Return code: 0
05:33:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
05:33: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')]}
05:33:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33: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')]}
05:33:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
05:33:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
05:33:00 INFO - Using env: {'DISPLAY': ':0',
05:33:00 INFO - 'HOME': '/home/cltbld',
05:33:00 INFO - 'LANG': 'en_US.UTF-8',
05:33:00 INFO - 'LANGUAGE': 'en_US:en',
05:33:00 INFO - 'LOGNAME': 'cltbld',
05:33:00 INFO - 'MAIL': '/var/mail/cltbld',
05:33:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:00 INFO - 'MOZ_NO_REMOTE': '1',
05:33:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:00 INFO - 'NO_EM_RESTART': '1',
05:33:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:00 INFO - 'PWD': '/builds/slave/test',
05:33:00 INFO - 'SHELL': '/bin/bash',
05:33:00 INFO - 'SHLVL': '1',
05:33:00 INFO - 'TERM': 'linux',
05:33:00 INFO - 'TMOUT': '86400',
05:33:00 INFO - 'USER': 'cltbld',
05:33:00 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:00 INFO - '_': '/tools/buildbot/bin/python'}
05:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:01 INFO - Collecting mozsystemmonitor==0.3
05:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
05:33:02 INFO - Installing collected packages: mozsystemmonitor
05:33:02 INFO - Successfully installed mozsystemmonitor-0.3
05:33:02 INFO - Return code: 0
05:33:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
05:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
05:33:02 INFO - Using env: {'DISPLAY': ':0',
05:33:02 INFO - 'HOME': '/home/cltbld',
05:33:02 INFO - 'LANG': 'en_US.UTF-8',
05:33:02 INFO - 'LANGUAGE': 'en_US:en',
05:33:02 INFO - 'LOGNAME': 'cltbld',
05:33:02 INFO - 'MAIL': '/var/mail/cltbld',
05:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:02 INFO - 'MOZ_NO_REMOTE': '1',
05:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:02 INFO - 'NO_EM_RESTART': '1',
05:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:02 INFO - 'PWD': '/builds/slave/test',
05:33:02 INFO - 'SHELL': '/bin/bash',
05:33:02 INFO - 'SHLVL': '1',
05:33:02 INFO - 'TERM': 'linux',
05:33:02 INFO - 'TMOUT': '86400',
05:33:02 INFO - 'USER': 'cltbld',
05:33:02 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:02 INFO - '_': '/tools/buildbot/bin/python'}
05:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:02 INFO - Collecting jsonschema==2.5.1
05:33:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
05:33:03 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
05:33:04 INFO - Installing collected packages: functools32, jsonschema
05:33:04 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
05:33:04 INFO - Return code: 0
05:33:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
05:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
05:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
05:33:04 INFO - Using env: {'DISPLAY': ':0',
05:33:04 INFO - 'HOME': '/home/cltbld',
05:33:04 INFO - 'LANG': 'en_US.UTF-8',
05:33:04 INFO - 'LANGUAGE': 'en_US:en',
05:33:04 INFO - 'LOGNAME': 'cltbld',
05:33:04 INFO - 'MAIL': '/var/mail/cltbld',
05:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:04 INFO - 'MOZ_NO_REMOTE': '1',
05:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:04 INFO - 'NO_EM_RESTART': '1',
05:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:04 INFO - 'PWD': '/builds/slave/test',
05:33:04 INFO - 'SHELL': '/bin/bash',
05:33:04 INFO - 'SHLVL': '1',
05:33:04 INFO - 'TERM': 'linux',
05:33:04 INFO - 'TMOUT': '86400',
05:33:04 INFO - 'USER': 'cltbld',
05:33:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:04 INFO - '_': '/tools/buildbot/bin/python'}
05:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
05:33:05 INFO - Return code: 0
05:33:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
05:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
05:33:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
05:33:05 INFO - Using env: {'DISPLAY': ':0',
05:33:05 INFO - 'HOME': '/home/cltbld',
05:33:05 INFO - 'LANG': 'en_US.UTF-8',
05:33:05 INFO - 'LANGUAGE': 'en_US:en',
05:33:05 INFO - 'LOGNAME': 'cltbld',
05:33:05 INFO - 'MAIL': '/var/mail/cltbld',
05:33:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:05 INFO - 'MOZ_NO_REMOTE': '1',
05:33:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:05 INFO - 'NO_EM_RESTART': '1',
05:33:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:05 INFO - 'PWD': '/builds/slave/test',
05:33:05 INFO - 'SHELL': '/bin/bash',
05:33:05 INFO - 'SHLVL': '1',
05:33:05 INFO - 'TERM': 'linux',
05:33:05 INFO - 'TMOUT': '86400',
05:33:05 INFO - 'USER': 'cltbld',
05:33:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:05 INFO - '_': '/tools/buildbot/bin/python'}
05:33:05 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:05 INFO - Collecting blobuploader==1.2.4
05:33:06 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
05:33:07 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
05:33:08 INFO - Installing collected packages: requests, docopt, blobuploader
05:33:09 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
05:33:09 INFO - Return code: 0
05:33:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv
05:33:09 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')]}
05:33:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33:09 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')]}
05:33:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:09 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 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:09 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
05:33:09 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
05:33:09 INFO - Using env: {'DISPLAY': ':0',
05:33:09 INFO - 'HOME': '/home/cltbld',
05:33:09 INFO - 'LANG': 'en_US.UTF-8',
05:33:09 INFO - 'LANGUAGE': 'en_US:en',
05:33:09 INFO - 'LOGNAME': 'cltbld',
05:33:09 INFO - 'MAIL': '/var/mail/cltbld',
05:33:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:09 INFO - 'MOZ_NO_REMOTE': '1',
05:33:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:09 INFO - 'NO_EM_RESTART': '1',
05:33:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:09 INFO - 'PWD': '/builds/slave/test',
05:33:09 INFO - 'SHELL': '/bin/bash',
05:33:09 INFO - 'SHLVL': '1',
05:33:09 INFO - 'TERM': 'linux',
05:33:09 INFO - 'TMOUT': '86400',
05:33:09 INFO - 'USER': 'cltbld',
05:33:09 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:09 INFO - '_': '/tools/buildbot/bin/python'}
05:33:09 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
05:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
05:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
05:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
05:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
05:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
05:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
05:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
05:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
05:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
05:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
05:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
05:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
05:33:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
05:33:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
05:33:12 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
05:33:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
05:33:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
05:33:12 INFO - Running setup.py install for manifestparser: started
05:33:13 INFO - Running setup.py install for manifestparser: finished with status 'done'
05:33:13 INFO - Running setup.py install for mozcrash: started
05:33:13 INFO - Running setup.py install for mozcrash: finished with status 'done'
05:33:13 INFO - Running setup.py install for mozdebug: started
05:33:13 INFO - Running setup.py install for mozdebug: finished with status 'done'
05:33:13 INFO - Running setup.py install for mozdevice: started
05:33:13 INFO - Running setup.py install for mozdevice: finished with status 'done'
05:33:13 INFO - Running setup.py install for mozfile: started
05:33:13 INFO - Running setup.py install for mozfile: finished with status 'done'
05:33:13 INFO - Running setup.py install for mozhttpd: started
05:33:14 INFO - Running setup.py install for mozhttpd: finished with status 'done'
05:33:14 INFO - Running setup.py install for mozinfo: started
05:33:14 INFO - Running setup.py install for mozinfo: finished with status 'done'
05:33:14 INFO - Running setup.py install for mozInstall: started
05:33:14 INFO - Running setup.py install for mozInstall: finished with status 'done'
05:33:14 INFO - Running setup.py install for mozleak: started
05:33:14 INFO - Running setup.py install for mozleak: finished with status 'done'
05:33:14 INFO - Running setup.py install for mozlog: started
05:33:15 INFO - Running setup.py install for mozlog: finished with status 'done'
05:33:15 INFO - Running setup.py install for moznetwork: started
05:33:15 INFO - Running setup.py install for moznetwork: finished with status 'done'
05:33:15 INFO - Running setup.py install for mozprocess: started
05:33:15 INFO - Running setup.py install for mozprocess: finished with status 'done'
05:33:15 INFO - Running setup.py install for mozprofile: started
05:33:15 INFO - Running setup.py install for mozprofile: finished with status 'done'
05:33:15 INFO - Running setup.py install for mozrunner: started
05:33:16 INFO - Running setup.py install for mozrunner: finished with status 'done'
05:33:16 INFO - Running setup.py install for mozscreenshot: started
05:33:16 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
05:33:16 INFO - Running setup.py install for moztest: started
05:33:16 INFO - Running setup.py install for moztest: finished with status 'done'
05:33:16 INFO - Running setup.py install for mozversion: started
05:33:16 INFO - Running setup.py install for mozversion: finished with status 'done'
05:33:16 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
05:33:16 INFO - Return code: 0
05:33:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
05:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:17 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 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:17 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
05:33:17 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
05:33:17 INFO - Using env: {'DISPLAY': ':0',
05:33:17 INFO - 'HOME': '/home/cltbld',
05:33:17 INFO - 'LANG': 'en_US.UTF-8',
05:33:17 INFO - 'LANGUAGE': 'en_US:en',
05:33:17 INFO - 'LOGNAME': 'cltbld',
05:33:17 INFO - 'MAIL': '/var/mail/cltbld',
05:33:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:17 INFO - 'MOZ_NO_REMOTE': '1',
05:33:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:17 INFO - 'NO_EM_RESTART': '1',
05:33:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:17 INFO - 'PWD': '/builds/slave/test',
05:33:17 INFO - 'SHELL': '/bin/bash',
05:33:17 INFO - 'SHLVL': '1',
05:33:17 INFO - 'TERM': 'linux',
05:33:17 INFO - 'TMOUT': '86400',
05:33:17 INFO - 'USER': 'cltbld',
05:33:17 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:17 INFO - '_': '/tools/buildbot/bin/python'}
05:33:17 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
05:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
05:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
05:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
05:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
05:33:18 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))
05:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
05:33:18 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))
05:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
05:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
05:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
05:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
05:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
05:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
05:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
05:33:19 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))
05:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
05:33:19 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))
05:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
05:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
05:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
05:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
05:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
05:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
05:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
05:33:20 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))
05:33:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
05:33:20 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))
05:33:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
05:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
05:33:20 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
05:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
05:33:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
05:33:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
05:33:20 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
05:33:22 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
05:33:22 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))
05:33:22 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))
05:33:22 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))
05:33:22 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))
05:33:22 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))
05:33:22 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))
05:33:22 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
05:33:23 INFO - Installing collected packages: six, blessings
05:33:23 INFO - Successfully installed blessings-1.6 six-1.10.0
05:33:23 INFO - Return code: 0
05:33:23 INFO - Done creating virtualenv /builds/slave/test/build/venv.
05:33:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
05:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
05:33:23 INFO - Reading from file tmpfile_stdout
05:33:23 INFO - Reading from file tmpfile_stderr
05:33:23 INFO - Current package versions:
05:33:23 INFO - blessings == 1.6
05:33:23 INFO - blobuploader == 1.2.4
05:33:23 INFO - docopt == 0.6.1
05:33:23 INFO - functools32 == 3.2.3.post2
05:33:23 INFO - jsonschema == 2.5.1
05:33:23 INFO - manifestparser == 1.2
05:33:23 INFO - mozInstall == 1.14
05:33:23 INFO - mozcrash == 1.0
05:33:23 INFO - mozdebug == 0.1
05:33:23 INFO - mozdevice == 0.51
05:33:23 INFO - mozfile == 1.2
05:33:23 INFO - mozhttpd == 0.7
05:33:23 INFO - mozinfo == 0.10
05:33:23 INFO - mozleak == 0.1
05:33:23 INFO - mozlog == 3.5
05:33:23 INFO - moznetwork == 0.27
05:33:23 INFO - mozprocess == 0.25
05:33:23 INFO - mozprofile == 0.29
05:33:23 INFO - mozrunner == 6.13
05:33:23 INFO - mozscreenshot == 0.1
05:33:23 INFO - mozsystemmonitor == 0.3
05:33:23 INFO - moztest == 0.8
05:33:23 INFO - mozversion == 1.4
05:33:23 INFO - psutil == 3.1.1
05:33:23 INFO - requests == 1.2.3
05:33:23 INFO - six == 1.10.0
05:33:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv
05:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
05:33:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
05:33:23 INFO - Using env: {'DISPLAY': ':0',
05:33:23 INFO - 'HOME': '/home/cltbld',
05:33:23 INFO - 'LANG': 'en_US.UTF-8',
05:33:23 INFO - 'LANGUAGE': 'en_US:en',
05:33:23 INFO - 'LOGNAME': 'cltbld',
05:33:23 INFO - 'MAIL': '/var/mail/cltbld',
05:33:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:23 INFO - 'MOZ_NO_REMOTE': '1',
05:33:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:23 INFO - 'NO_EM_RESTART': '1',
05:33:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:23 INFO - 'PWD': '/builds/slave/test',
05:33:23 INFO - 'SHELL': '/bin/bash',
05:33:23 INFO - 'SHLVL': '1',
05:33:23 INFO - 'TERM': 'linux',
05:33:23 INFO - 'TMOUT': '86400',
05:33:23 INFO - 'USER': 'cltbld',
05:33:23 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:23 INFO - '_': '/tools/buildbot/bin/python'}
05:33:24 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:24 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))
05:33:24 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))
05:33:24 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))
05:33:24 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))
05:33:24 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))
05:33:24 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))
05:33:24 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))
05:33:24 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))
05:33:24 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))
05:33:24 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
05:33:25 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
05:33:26 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))
05:33:26 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))
05:33:26 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))
05:33:26 INFO - Installing collected packages: simplejson, requests
05:33:26 INFO - Found existing installation: requests 1.2.3
05:33:26 INFO - Uninstalling requests-1.2.3:
05:33:26 INFO - Successfully uninstalled requests-1.2.3
05:33:26 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
05:33:26 INFO - Return code: 0
05:33:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
05:33: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')]}
05:33:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
05:33: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')]}
05:33:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
05:33:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22101f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23daa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x257dcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, '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': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307', '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
05:33:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
05:33:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
05:33:26 INFO - Using env: {'DISPLAY': ':0',
05:33:26 INFO - 'HOME': '/home/cltbld',
05:33:26 INFO - 'LANG': 'en_US.UTF-8',
05:33:26 INFO - 'LANGUAGE': 'en_US:en',
05:33:26 INFO - 'LOGNAME': 'cltbld',
05:33:26 INFO - 'MAIL': '/var/mail/cltbld',
05:33:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:26 INFO - 'MOZ_NO_REMOTE': '1',
05:33:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:26 INFO - 'NO_EM_RESTART': '1',
05:33:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:26 INFO - 'PWD': '/builds/slave/test',
05:33:26 INFO - 'SHELL': '/bin/bash',
05:33:26 INFO - 'SHLVL': '1',
05:33:26 INFO - 'TERM': 'linux',
05:33:26 INFO - 'TMOUT': '86400',
05:33:26 INFO - 'USER': 'cltbld',
05:33:26 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:26 INFO - '_': '/tools/buildbot/bin/python'}
05:33:27 INFO - Ignoring indexes: https://pypi.python.org/simple
05:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
05:33:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
05:33:27 INFO - Return code: 0
05:33:27 INFO - Running post-action listener: _resource_record_post_action
05:33:27 INFO - Running post-action listener: _start_resource_monitoring
05:33:27 INFO - Starting resource monitoring.
05:33:27 INFO - [mozharness: 2017-10-25 12:33:27.376311Z] Finished create-virtualenv step (success)
05:33:27 INFO - [mozharness: 2017-10-25 12:33:27.376764Z] Running install step.
05:33:27 INFO - Running pre-action listener: _resource_record_pre_action
05:33:27 INFO - Running main action method: install
05:33:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
05:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
05:33:27 INFO - Reading from file tmpfile_stdout
05:33:27 INFO - Reading from file tmpfile_stderr
05:33:27 INFO - Detecting whether we're running mozinstall >=1.0...
05:33:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
05:33:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
05:33:28 INFO - Reading from file tmpfile_stdout
05:33:28 INFO - Output received:
05:33:28 INFO - Usage: mozinstall [options] installer
05:33:28 INFO - Options:
05:33:28 INFO - -h, --help show this help message and exit
05:33:28 INFO - -d DEST, --destination=DEST
05:33:28 INFO - Directory to install application into. [default:
05:33:28 INFO - "/builds/slave/test"]
05:33:28 INFO - --app=APP Application being installed. [default: firefox]
05:33:28 INFO - mkdir: /builds/slave/test/build/application
05:33:28 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']
05:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
05:33:46 INFO - Reading from file tmpfile_stdout
05:33:46 INFO - Output received:
05:33:46 INFO - /builds/slave/test/build/application/firefox/firefox
05:33:46 INFO - Running post-action listener: _resource_record_post_action
05:33:46 INFO - [mozharness: 2017-10-25 12:33:46.168220Z] Finished install step (success)
05:33:46 INFO - [mozharness: 2017-10-25 12:33:46.168466Z] Running setup-mitmproxy step.
05:33:46 INFO - Running pre-action listener: _resource_record_pre_action
05:33:46 INFO - Running main action method: setup_mitmproxy
05:33:46 INFO - Skipping: mitmproxy is not required
05:33:46 INFO - Running post-action listener: _resource_record_post_action
05:33:46 INFO - [mozharness: 2017-10-25 12:33:46.169687Z] Finished setup-mitmproxy step (success)
05:33:46 INFO - [mozharness: 2017-10-25 12:33:46.169905Z] Running run-tests step.
05:33:46 INFO - Running pre-action listener: _resource_record_pre_action
05:33:46 INFO - Running pre-action listener: _set_gcov_prefix
05:33:46 INFO - Running main action method: run_tests
05:33:46 WARNING - Try message not found.
05:33:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
05:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
05:33:46 INFO - Python 2.7.3
05:33:46 INFO - Return code: 0
05:33:46 INFO - grabbing minidump binary from tooltool
05:33:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
05:33:46 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 0x257ae60>, '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 0x257bd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x257c1f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
05:33:46 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
05:33:46 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
05:33:46 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
05:33:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
05:33:46 INFO - Return code: 0
05:33:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
05:33:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
05:33:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
05:33:46 INFO - ENV: RUST_BACKTRACE is now full
05:33:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
05:33:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
05:33:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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
05:33:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --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
05:33:46 INFO - Using env: {'DISPLAY': ':0',
05:33:46 INFO - 'HOME': '/home/cltbld',
05:33:46 INFO - 'LANG': 'en_US.UTF-8',
05:33:46 INFO - 'LANGUAGE': 'en_US:en',
05:33:46 INFO - 'LOGNAME': 'cltbld',
05:33:46 INFO - 'MAIL': '/var/mail/cltbld',
05:33:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
05:33:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
05:33:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:46 INFO - 'MOZ_NO_REMOTE': '1',
05:33:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
05:33:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:46 INFO - 'NO_EM_RESTART': '1',
05:33:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:46 INFO - 'PWD': '/builds/slave/test',
05:33:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
05:33:46 INFO - 'RUST_BACKTRACE': 'full',
05:33:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
05:33:46 INFO - 'SHELL': '/bin/bash',
05:33:46 INFO - 'SHLVL': '1',
05:33:46 INFO - 'TERM': 'linux',
05:33:46 INFO - 'TMOUT': '86400',
05:33:46 INFO - 'USER': 'cltbld',
05:33:46 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:46 INFO - '_': '/tools/buildbot/bin/python'}
05:33:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--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
05:33:46 INFO - mozversion application_buildid: 20171025110203
05:33:46 INFO - mozversion application_changeset: 6e5e01b3f15f8d995707875d8d2243f9dae52f08
05:33:46 INFO - mozversion application_display_name: Nightly
05:33:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
05:33:46 INFO - mozversion application_name: Firefox
05:33:46 INFO - mozversion application_remotingname: firefox
05:33:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
05:33:46 INFO - mozversion application_vendor: Mozilla
05:33:46 INFO - mozversion application_version: 58.0a1
05:33:46 INFO - mozversion platform_buildid: 20171025110203
05:33:46 INFO - mozversion platform_changeset: 6e5e01b3f15f8d995707875d8d2243f9dae52f08
05:33:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
05:33:46 INFO - mozversion platform_version: 58.0a1
05:33:46 INFO - using testdate: 1508934826
05:33:46 INFO - actual date: 1508934826
05:33:46 INFO - starting webserver on 'localhost:37097'
05:33:46 INFO - SUITE-START | Running 2 tests
05:33:46 INFO - TEST-START | basic_compositor_video
05:33:46 INFO - Initialising browser for basic_compositor_video test...
05:33:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37097/getInfo.html -profile /tmp/tmpTHUZOk/profile
05:33:46 INFO - TEST-INFO | started process 3714 (/builds/slave/test/build/application/firefox/firefox http://localhost:37097/getInfo.html)
05:33:57 INFO - TEST-INFO | 3714: exit 0
05:33:58 INFO - Browser initialized.
05:33:58 INFO - Running cycle 1/1 for basic_compositor_video test...
05:33:58 INFO - Using env: {'DISPLAY': ':0',
05:33:58 INFO - 'HOME': '/home/cltbld',
05:33:58 INFO - 'JSGC_DISABLE_POISONING': '1',
05:33:58 INFO - 'LANG': 'en_US.UTF-8',
05:33:58 INFO - 'LANGUAGE': 'en_US:en',
05:33:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
05:33:58 INFO - 'LOGNAME': 'cltbld',
05:33:58 INFO - 'MAIL': '/var/mail/cltbld',
05:33:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
05:33:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
05:33:58 INFO - 'MOZ_CRASHREPORTER': '1',
05:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:33:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
05:33:58 INFO - 'MOZ_NO_REMOTE': '1',
05:33:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
05:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:33:58 INFO - 'NO_EM_RESTART': '1',
05:33:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:33:58 INFO - 'PWD': '/builds/slave/test',
05:33:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
05:33:58 INFO - 'RUST_BACKTRACE': 'full',
05:33:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
05:33:58 INFO - 'SHELL': '/bin/bash',
05:33:58 INFO - 'SHLVL': '1',
05:33:58 INFO - 'STYLO_FORCE_DISABLED': '1',
05:33:58 INFO - 'TERM': 'linux',
05:33:58 INFO - 'TMOUT': '86400',
05:33:58 INFO - 'USER': 'cltbld',
05:33:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:33:58 INFO - '_': '/tools/buildbot/bin/python'}
05:33:58 INFO - TEST-INFO | started process 3952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTHUZOk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
05:33:58 INFO - PID 3952 |
05:33:58 INFO - PID 3952 | (/builds/slave/test/build/application/firefox/firefox:4003): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
05:33:58 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.679065472859541 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674125000000004 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7244367816091957 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6935055038103308 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.132479338842976 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9458678500986197 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.619650180940891 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5222623239436626 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.781416184971099 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.602422969187671 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.239368600682589 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 9.855837438423649 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.196175373134318 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81554054054054 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.451763485477178 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054367469879526 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.859604743083016 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.176061452513963 ms/frame
05:34:45 INFO - PID 3952 |
05:34:45 INFO - PID 3952 | Cycle 1(1): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:35:31 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6905098591549297 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6695742904841404 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.762643948296122 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.716965665236051 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.161955617198336 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.107987679671458 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.6025030012004806 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5093596491228056 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.556027777777779 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587318435754185 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.346741379310346 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 9.854827586206891 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.282048872180457 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639122340425526 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.452136929460597 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766764705882345 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.587258687258682 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.755618279569923 ms/frame
05:35:31 INFO - PID 3952 |
05:35:31 INFO - PID 3952 | Cycle 1(2): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:36:17 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681796524663677 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6668166666666668 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7600674486803518 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7439319965126425 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116550068587106 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.009018036072145 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.5853942652329773 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.521223591549296 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.748706896551726 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.732077363896845 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.384117647058819 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.812513513513526 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.363920454545458 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930245901639347 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.768553191489364 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83733727810652 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 12.048975903614457 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.494885057471267 ms/frame
05:36:17 INFO - PID 3952 |
05:36:17 INFO - PID 3952 | Cycle 1(3): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:37:03 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6847108366086467 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686196830692246 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7682969946965235 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7926344086021504 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.155886426592796 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.20186974789916 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.4408314220183494 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.378429054054052 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.486535648994519 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.54019390581717 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.102289562289554 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.526236842105266 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.235973782771554 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.811081081081058 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.65997890295356 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.276257668711693 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49490421455938 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.173882681564246 ms/frame
05:37:03 INFO - PID 3952 |
05:37:03 INFO - PID 3952 | Cycle 1(4): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:37:50 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.67978163493841 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6700417362270452 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7504725787631268 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.773102836879433 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.156655124653739 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.228837209302325 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.525640423031727 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5213996478873284 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.58842644320298 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620379213483145 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.455452961672474 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758609756097576 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.324433962264145 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.933306010928993 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606932773109232 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.351882716049367 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.812066929133856 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.2369943820225 ms/frame
05:37:50 INFO - PID 3952 |
05:37:50 INFO - PID 3952 | Cycle 1(5): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:38:36 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6764497206703912 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686822351959967 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7815736342042756 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7640388007054675 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1054445964432285 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.219873417721521 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.56847205707491 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5592971530249122 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683200757575757 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.666104815864029 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.101851851851851 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.313170103092789 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.236273408239697 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.642340425531925 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.931400862068962 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.346666666666689 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.719609375000005 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.240084269662924 ms/frame
05:38:36 INFO - PID 3952 |
05:38:36 INFO - PID 3952 | Cycle 1(6): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:38:58 INFO - PID 3952 |
05:38:58 INFO - PID 3952 | (/builds/slave/test/build/application/firefox/firefox:4072): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
05:38:58 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686531759415402 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672500000000001 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.756545667447307 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7454013961605588 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.190642458100559 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.115380658436216 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.559661921708183 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.473906250000002 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.525920810313077 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.466202185792342 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.566690140845067 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.754623655913981 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.36704545454546 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.872201086956524 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.932349137931036 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.838994082840243 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.859881422924913 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.301468926553676 ms/frame
05:39:22 INFO - PID 3952 |
05:39:22 INFO - PID 3952 | Cycle 1(7): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:40:08 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680296752519597 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6730936454849499 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7611208920187793 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7532602979842247 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9428055190538744 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9851394422310773 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.538266509433961 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4082623509369703 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769192307692307 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.377150537634416 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.60266784452297 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.311778350515468 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.408992395437258 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.932185792349747 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.767170212765942 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.423105590062129 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.90837301587303 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.178491620111716 ms/frame
05:40:08 INFO - PID 3952 |
05:40:08 INFO - PID 3952 | Cycle 1(8): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:40:54 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827005047672463 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6696494156928212 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7436257989540964 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7520534150612963 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.049473684210526 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.143126293995861 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.448545977011495 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.385795262267345 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704961977186314 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.651892655367233 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 11.07062730627306 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 9.953955223880591 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.409220532319392 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.813702702702722 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.349526748971185 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.124242424242425 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.81411417322836 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.365482954545438 ms/frame
05:40:54 INFO - PID 3952 |
05:40:54 INFO - PID 3952 | Cycle 1(9): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:41:40 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6847697922515443 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6726588628762538 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.800765306122449 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7954129263913816 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9025227568270493 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.13262396694215 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.5179953106682325 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.414300341296926 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769548076923079 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.714114285714282 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.56415492957747 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.421562499999993 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.281522556390971 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.643377659574478 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.554769874476973 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.124090909090901 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.631608527131789 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11413888888888 ms/frame
05:41:40 INFO - PID 3952 |
05:41:40 INFO - PID 3952 | Cycle 1(10): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:42:27 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827565900168255 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698998330550918 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.790277446300716 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7499037620297457 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.082102040816325 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.796337760910816 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.6549390986601726 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5591014234875447 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619765917602993 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.465806010928963 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.309243986254302 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.30935567010309 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.281879699248105 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.814054054054086 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.609033613445396 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.126999999999967 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.718906250000003 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.365738636363652 ms/frame
05:42:27 INFO - PID 3952 |
05:42:27 INFO - PID 3952 | Cycle 1(11): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:43:13 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6922165820642978 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6659991673605328 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7700206489675514 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7337131715771237 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_startup = 4.016419009370816 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.074114052953155 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_startup = 3.4930791618160635 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4194957264957306 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650499058380409 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587877094972074 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_startup = 10.00155 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.480p.60fps.webm_scale_2_inclip = 10.004225000000005 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.153048327137553 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.049723756906078 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.766085106382974 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.350308641975309 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.954721115537856 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.430685714285687 ms/frame
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | Cycle 1(12): loaded http://localhost:37097/tests/video/video_playback.html (next: http://localhost:37097/tests/video/video_playback.html)
05:43:13 INFO - PID 3952 | __start_tp_report
05:43:13 INFO - PID 3952 | _x_x_mozilla_page_load
05:43:13 INFO - PID 3952 | _x_x_mozilla_page_load_details
05:43:13 INFO - PID 3952 | |i|pagename|runs|
05:43:13 INFO - PID 3952 | |0;240p.120fps.mp4_scale_1_startup;1.679065472859541;1.6905098591549297;1.681796524663677;1.6847108366086467;1.67978163493841;1.6764497206703912;1.686531759415402;1.680296752519597;1.6827005047672463;1.6847697922515443;1.6827565900168255;1.6922165820642978
05:43:13 INFO - PID 3952 | |1;240p.120fps.mp4_scale_1_inclip;1.6674125000000004;1.6695742904841404;1.6668166666666668;1.6686196830692246;1.6700417362270452;1.6686822351959967;1.6672500000000001;1.6730936454849499;1.6696494156928212;1.6726588628762538;1.6698998330550918;1.6659991673605328
05:43:13 INFO - PID 3952 | |2;240p.120fps.mp4_scale_1.1_startup;1.7244367816091957;1.762643948296122;1.7600674486803518;1.7682969946965235;1.7504725787631268;1.7815736342042756;1.756545667447307;1.7611208920187793;1.7436257989540964;1.800765306122449;1.790277446300716;1.7700206489675514
05:43:13 INFO - PID 3952 | |3;240p.120fps.mp4_scale_1.1_inclip;1.6935055038103308;1.716965665236051;1.7439319965126425;1.7926344086021504;1.773102836879433;1.7640388007054675;1.7454013961605588;1.7532602979842247;1.7520534150612963;1.7954129263913816;1.7499037620297457;1.7337131715771237
05:43:13 INFO - PID 3952 | |4;240p.120fps.mp4_scale_2_startup;4.132479338842976;4.161955617198336;4.116550068587106;4.155886426592796;4.156655124653739;4.1054445964432285;4.190642458100559;3.9428055190538744;4.049473684210526;3.9025227568270493;4.082102040816325;4.016419009370816
05:43:13 INFO - PID 3952 | |5;240p.120fps.mp4_scale_2_inclip;3.9458678500986197;4.107987679671458;4.009018036072145;4.20186974789916;4.228837209302325;4.219873417721521;4.115380658436216;3.9851394422310773;4.143126293995861;4.13262396694215;3.796337760910816;4.074114052953155
05:43:13 INFO - PID 3952 | |6;480p.60fps.webm_scale_1_startup;3.619650180940891;3.6025030012004806;3.5853942652329773;3.4408314220183494;3.525640423031727;3.56847205707491;3.559661921708183;3.538266509433961;3.448545977011495;3.5179953106682325;3.6549390986601726;3.4930791618160635
05:43:13 INFO - PID 3952 | |7;480p.60fps.webm_scale_1_inclip;3.5222623239436626;3.5093596491228056;3.521223591549296;3.378429054054052;3.5213996478873284;3.5592971530249122;3.473906250000002;3.4082623509369703;3.385795262267345;3.414300341296926;3.5591014234875447;3.4194957264957306
05:43:13 INFO - PID 3952 | |8;480p.60fps.webm_scale_1.1_startup;5.781416184971099;5.556027777777779;5.748706896551726;5.486535648994519;5.58842644320298;5.683200757575757;5.525920810313077;5.769192307692307;5.704961977186314;5.769548076923079;5.619765917602993;5.650499058380409
05:43:13 INFO - PID 3952 | |9;480p.60fps.webm_scale_1.1_inclip;5.602422969187671;5.587318435754185;5.732077363896845;5.54019390581717;5.620379213483145;5.666104815864029;5.466202185792342;5.377150537634416;5.651892655367233;5.714114285714282;5.465806010928963;5.587877094972074
05:43:13 INFO - PID 3952 | |10;480p.60fps.webm_scale_2_startup;10.239368600682589;10.346741379310346;10.384117647058819;10.102289562289554;10.455452961672474;10.101851851851851;10.566690140845067;10.60266784452297;11.07062730627306;10.56415492957747;10.309243986254302;10.00155
05:43:13 INFO - PID 3952 | |11;480p.60fps.webm_scale_2_inclip;9.855837438423649;9.854827586206891;10.812513513513526;10.526236842105266;9.758609756097576;10.313170103092789;10.754623655913981;10.311778350515468;9.953955223880591;10.421562499999993;10.30935567010309;10.004225000000005
05:43:13 INFO - PID 3952 | |12;1080p.60fps.mp4_scale_1_startup;11.196175373134318;11.282048872180457;11.363920454545458;11.235973782771554;11.324433962264145;11.236273408239697;11.36704545454546;11.408992395437258;11.409220532319392;11.281522556390971;11.281879699248105;11.153048327137553
05:43:13 INFO - PID 3952 | |13;1080p.60fps.mp4_scale_1_inclip;10.81554054054054;10.639122340425526;10.930245901639347;10.811081081081058;10.933306010928993;10.642340425531925;10.872201086956524;10.932185792349747;10.813702702702722;10.643377659574478;10.814054054054086;11.049723756906078
05:43:13 INFO - PID 3952 | |14;1080p.60fps.mp4_scale_1.1_startup;12.451763485477178;12.452136929460597;12.768553191489364;12.65997890295356;12.606932773109232;12.931400862068962;12.932349137931036;12.767170212765942;12.349526748971185;12.554769874476973;12.609033613445396;12.766085106382974
05:43:13 INFO - PID 3952 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.054367469879526;11.766764705882345;11.83733727810652;12.276257668711693;12.351882716049367;12.346666666666689;11.838994082840243;12.423105590062129;12.124242424242425;12.124090909090901;12.126999999999967;12.350308641975309
05:43:13 INFO - PID 3952 | |16;1080p.60fps.mp4_scale_2_startup;11.859604743083016;11.587258687258682;12.048975903614457;11.49490421455938;11.812066929133856;11.719609375000005;11.859881422924913;11.90837301587303;11.81411417322836;11.631608527131789;11.718906250000003;11.954721115537856
05:43:13 INFO - PID 3952 | |17;1080p.60fps.mp4_scale_2_inclip;11.176061452513963;10.755618279569923;11.494885057471267;11.173882681564246;11.2369943820225;11.240084269662924;11.301468926553676;11.178491620111716;11.365482954545438;11.11413888888888;11.365738636363652;11.430685714285687
05:43:13 INFO - PID 3952 | __end_tp_report
05:43:13 INFO - PID 3952 | __start_cc_report
05:43:13 INFO - PID 3952 | _x_x_mozilla_cycle_collect,549
05:43:13 INFO - PID 3952 | __end_cc_report
05:43:13 INFO - PID 3952 | __startTimestamp1508935393381__endTimestamp
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | ------- Summary: start -------
05:43:13 INFO - PID 3952 | Number of tests: 18
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
05:43:13 INFO - PID 3952 | 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
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
05:43:13 INFO - PID 3952 | 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
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.77 stddev:0.02 (1.2%) stddev-sans-first:0.02
05:43:13 INFO - PID 3952 | Values: 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.7 1.8 1.8 1.8
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.75 stddev:0.03 (1.7%) stddev-sans-first:0.02
05:43:13 INFO - PID 3952 | Values: 1.7 1.7 1.7 1.8 1.8 1.8 1.7 1.8 1.8 1.8 1.7 1.7
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.08 Median:4.12 stddev:0.09 (2.2%) stddev-sans-first:0.09
05:43:13 INFO - PID 3952 | Values: 4.1 4.2 4.1 4.2 4.2 4.1 4.2 3.9 4.0 3.9 4.1 4.0
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.08 Median:4.12 stddev:0.13 (3.1%) stddev-sans-first:0.13
05:43:13 INFO - PID 3952 | Values: 3.9 4.1 4.0 4.2 4.2 4.2 4.1 4.0 4.1 4.1 3.8 4.1
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.55 Median:3.56 stddev:0.07 (1.8%) stddev-sans-first:0.06
05:43:13 INFO - PID 3952 | Values: 3.6 3.6 3.6 3.4 3.5 3.6 3.6 3.5 3.4 3.5 3.7 3.5
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.47 Median:3.52 stddev:0.07 (1.9%) stddev-sans-first:0.07
05:43:13 INFO - PID 3952 | Values: 3.5 3.5 3.5 3.4 3.5 3.6 3.5 3.4 3.4 3.4 3.6 3.4
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.66 Median:5.69 stddev:0.10 (1.8%) stddev-sans-first:0.10
05:43:13 INFO - PID 3952 | Values: 5.8 5.6 5.7 5.5 5.6 5.7 5.5 5.8 5.7 5.8 5.6 5.7
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.58 Median:5.61 stddev:0.11 (1.9%) stddev-sans-first:0.11
05:43:13 INFO - PID 3952 | Values: 5.6 5.6 5.7 5.5 5.6 5.7 5.5 5.4 5.7 5.7 5.5 5.6
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.40 Median:10.42 stddev:0.29 (2.8%) stddev-sans-first:0.30
05:43:13 INFO - PID 3952 | Values: 10.2 10.3 10.4 10.1 10.5 10.1 10.6 10.6 11.1 10.6 10.3 10.0
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.24 Median:10.78 stddev:0.36 (3.3%) stddev-sans-first:0.35
05:43:13 INFO - PID 3952 | Values: 9.9 9.9 10.8 10.5 9.8 10.3 10.8 10.3 10.0 10.4 10.3 10.0
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.30 Median:11.30 stddev:0.08 (0.7%) stddev-sans-first:0.08
05:43:13 INFO - PID 3952 | Values: 11.2 11.3 11.4 11.2 11.3 11.2 11.4 11.4 11.4 11.3 11.3 11.2
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.82 Median:10.84 stddev:0.13 (1.2%) stddev-sans-first:0.14
05:43:13 INFO - PID 3952 | Values: 10.8 10.6 10.9 10.8 10.9 10.6 10.9 10.9 10.8 10.6 10.8 11.0
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.65 Median:12.71 stddev:0.19 (1.5%) stddev-sans-first:0.18
05:43:13 INFO - PID 3952 | Values: 12.5 12.5 12.8 12.7 12.6 12.9 12.9 12.8 12.3 12.6 12.6 12.8
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.14 Median:12.20 stddev:0.23 (1.8%) stddev-sans-first:0.24
05:43:13 INFO - PID 3952 | Values: 12.1 11.8 11.8 12.3 12.4 12.3 11.8 12.4 12.1 12.1 12.1 12.4
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.78 Median:11.84 stddev:0.16 (1.4%) stddev-sans-first:0.17
05:43:13 INFO - PID 3952 | Values: 11.9 11.6 12.0 11.5 11.8 11.7 11.9 11.9 11.8 11.6 11.7 12.0
05:43:13 INFO - PID 3952 |
05:43:13 INFO - PID 3952 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.24 Median:11.27 stddev:0.19 (1.7%) stddev-sans-first:0.20
05:43:13 INFO - PID 3952 | Values: 11.2 10.8 11.5 11.2 11.2 11.2 11.3 11.2 11.4 11.1 11.4 11.4
05:43:13 INFO - PID 3952 | -------- Summary: end --------
05:43:13 INFO - PID 3952 |
05:43:13 INFO - TEST-INFO | 3952: exit 0
05:43:13 INFO - TEST-OK | basic_compositor_video | took 567270ms
05:43:13 INFO - TEST-START | glvideo
05:43:13 INFO - Initialising browser for glvideo test...
05:43:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37097/getInfo.html -profile /tmp/tmpxaQqqW/profile
05:43:13 INFO - TEST-INFO | started process 5245 (/builds/slave/test/build/application/firefox/firefox http://localhost:37097/getInfo.html)
05:43:20 INFO - TEST-INFO | 5245: exit 0
05:43:20 INFO - Browser initialized.
05:43:20 INFO - Running cycle 1/1 for glvideo test...
05:43:20 INFO - Using env: {'DISPLAY': ':0',
05:43:20 INFO - 'HOME': '/home/cltbld',
05:43:20 INFO - 'JSGC_DISABLE_POISONING': '1',
05:43:20 INFO - 'LANG': 'en_US.UTF-8',
05:43:20 INFO - 'LANGUAGE': 'en_US:en',
05:43:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
05:43:20 INFO - 'LOGNAME': 'cltbld',
05:43:20 INFO - 'MAIL': '/var/mail/cltbld',
05:43:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
05:43:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
05:43:20 INFO - 'MOZ_CRASHREPORTER': '1',
05:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
05:43:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
05:43:20 INFO - 'MOZ_NO_REMOTE': '1',
05:43:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
05:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
05:43:20 INFO - 'NO_EM_RESTART': '1',
05:43:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
05:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
05:43:20 INFO - 'PWD': '/builds/slave/test',
05:43:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
05:43:20 INFO - 'RUST_BACKTRACE': 'full',
05:43:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
05:43:20 INFO - 'SHELL': '/bin/bash',
05:43:20 INFO - 'SHLVL': '1',
05:43:20 INFO - 'STYLO_FORCE_DISABLED': '1',
05:43:20 INFO - 'TERM': 'linux',
05:43:20 INFO - 'TMOUT': '86400',
05:43:20 INFO - 'USER': 'cltbld',
05:43:20 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1508934253.220159-468482307',
05:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
05:43:20 INFO - '_': '/tools/buildbot/bin/python'}
05:43:20 INFO - TEST-INFO | started process 5517 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxaQqqW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
05:43:21 INFO - PID 5517 |
05:43:21 INFO - PID 5517 | (/builds/slave/test/build/application/firefox/firefox:5569): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
05:43:21 INFO - PID 5517 |
05:43:22 INFO - PID 5517 | [talos glvideo result] Mean tick time across 100 ticks: 7.570250000000001 ms
05:43:22 INFO - PID 5517 | Cycle 1(1): loaded http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html)
05:43:24 INFO - PID 5517 | [talos glvideo result] Mean tick time across 100 ticks: 6.476750000000001 ms
05:43:24 INFO - PID 5517 | Cycle 1(2): loaded http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html)
05:43:25 INFO - PID 5517 | [talos glvideo result] Mean tick time across 100 ticks: 6.50995 ms
05:43:25 INFO - PID 5517 | Cycle 1(3): loaded http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html)
05:43:27 INFO - PID 5517 | [talos glvideo result] Mean tick time across 100 ticks: 6.646699999999999 ms
05:43:27 INFO - PID 5517 | Cycle 1(4): loaded http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html)
05:43:28 INFO - PID 5517 | [talos glvideo result] Mean tick time across 100 ticks: 6.665749999999999 ms
05:43:28 INFO - PID 5517 | Cycle 1(5): loaded http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37097/tests/webgl/benchmarks/video/video_upload.html)
05:43:28 INFO - PID 5517 | __start_tp_report
05:43:28 INFO - PID 5517 | _x_x_mozilla_page_load
05:43:28 INFO - PID 5517 | _x_x_mozilla_page_load_details
05:43:28 INFO - PID 5517 | |i|pagename|runs|
05:43:28 INFO - PID 5517 | |0;Mean tick time across 100 ticks: ;7.570250000000001;6.476750000000001;6.50995;6.646699999999999;6.665749999999999
05:43:28 INFO - PID 5517 | __end_tp_report
05:43:28 INFO - PID 5517 | __start_cc_report
05:43:28 INFO - PID 5517 | _x_x_mozilla_cycle_collect,271
05:43:28 INFO - PID 5517 | __end_cc_report
05:43:28 INFO - PID 5517 | __startTimestamp1508935408638__endTimestamp
05:43:28 INFO - PID 5517 |
05:43:28 INFO - PID 5517 | ------- Summary: start -------
05:43:28 INFO - PID 5517 | Number of tests: 1
05:43:28 INFO - PID 5517 |
05:43:28 INFO - PID 5517 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.77 Median:6.65 stddev:0.45 (6.8%) stddev-sans-first:0.10
05:43:28 INFO - PID 5517 | Values: 7.6 6.5 6.5 6.6 6.7
05:43:28 INFO - PID 5517 | -------- Summary: end --------
05:43:28 INFO - PID 5517 |
05:43:29 INFO - PID 5517 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
05:43:29 INFO - TEST-INFO | 5517: exit 0
05:43:29 INFO - TEST-OK | glvideo | took 15322ms
05:43:29 INFO - SUITE-END | took 582s
05:43:29 INFO - Completed test suite (00:09:42)
05:43:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.660064930985715, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.679065472859541, 1.6905098591549297, 1.681796524663677, 1.6847108366086467, 1.67978163493841, 1.6764497206703912, 1.686531759415402, 1.680296752519597, 1.6827005047672463, 1.6847697922515443, 1.6827565900168255, 1.6922165820642978], "value": 1.6827565900168255, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6674125000000004, 1.6695742904841404, 1.6668166666666668, 1.6686196830692246, 1.6700417362270452, 1.6686822351959967, 1.6672500000000001, 1.6730936454849499, 1.6696494156928212, 1.6726588628762538, 1.6698998330550918, 1.6659991673605328], "value": 1.6695742904841404, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7244367816091957, 1.762643948296122, 1.7600674486803518, 1.7682969946965235, 1.7504725787631268, 1.7815736342042756, 1.756545667447307, 1.7611208920187793, 1.7436257989540964, 1.800765306122449, 1.790277446300716, 1.7700206489675514], "value": 1.762643948296122, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6935055038103308, 1.716965665236051, 1.7439319965126425, 1.7926344086021504, 1.773102836879433, 1.7640388007054675, 1.7454013961605588, 1.7532602979842247, 1.7520534150612963, 1.7954129263913816, 1.7499037620297457, 1.7337131715771237], "value": 1.7520534150612963, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.132479338842976, 4.161955617198336, 4.116550068587106, 4.155886426592796, 4.156655124653739, 4.1054445964432285, 4.190642458100559, 3.9428055190538744, 4.049473684210526, 3.9025227568270493, 4.082102040816325, 4.016419009370816], "value": 4.1054445964432285, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9458678500986197, 4.107987679671458, 4.009018036072145, 4.20186974789916, 4.228837209302325, 4.219873417721521, 4.115380658436216, 3.9851394422310773, 4.143126293995861, 4.13262396694215, 3.796337760910816, 4.074114052953155], "value": 4.115380658436216, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.619650180940891, 3.6025030012004806, 3.5853942652329773, 3.4408314220183494, 3.525640423031727, 3.56847205707491, 3.559661921708183, 3.538266509433961, 3.448545977011495, 3.5179953106682325, 3.6549390986601726, 3.4930791618160635], "value": 3.538266509433961, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5222623239436626, 3.5093596491228056, 3.521223591549296, 3.378429054054052, 3.5213996478873284, 3.5592971530249122, 3.473906250000002, 3.4082623509369703, 3.385795262267345, 3.414300341296926, 3.5591014234875447, 3.4194957264957306], "value": 3.473906250000002, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.781416184971099, 5.556027777777779, 5.748706896551726, 5.486535648994519, 5.58842644320298, 5.683200757575757, 5.525920810313077, 5.769192307692307, 5.704961977186314, 5.769548076923079, 5.619765917602993, 5.650499058380409], "value": 5.650499058380409, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.602422969187671, 5.587318435754185, 5.732077363896845, 5.54019390581717, 5.620379213483145, 5.666104815864029, 5.466202185792342, 5.377150537634416, 5.651892655367233, 5.714114285714282, 5.465806010928963, 5.587877094972074], "value": 5.587877094972074, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.239368600682589, 10.346741379310346, 10.384117647058819, 10.102289562289554, 10.455452961672474, 10.101851851851851, 10.566690140845067, 10.60266784452297, 11.07062730627306, 10.56415492957747, 10.309243986254302, 10.00155], "value": 10.384117647058819, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.855837438423649, 9.854827586206891, 10.812513513513526, 10.526236842105266, 9.758609756097576, 10.313170103092789, 10.754623655913981, 10.311778350515468, 9.953955223880591, 10.421562499999993, 10.30935567010309, 10.004225000000005], "value": 10.311778350515468, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.196175373134318, 11.282048872180457, 11.363920454545458, 11.235973782771554, 11.324433962264145, 11.236273408239697, 11.36704545454546, 11.408992395437258, 11.409220532319392, 11.281522556390971, 11.281879699248105, 11.153048327137553], "value": 11.282048872180457, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.81554054054054, 10.639122340425526, 10.930245901639347, 10.811081081081058, 10.933306010928993, 10.642340425531925, 10.872201086956524, 10.932185792349747, 10.813702702702722, 10.643377659574478, 10.814054054054086, 11.049723756906078], "value": 10.814054054054086, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.451763485477178, 12.452136929460597, 12.768553191489364, 12.65997890295356, 12.606932773109232, 12.931400862068962, 12.932349137931036, 12.767170212765942, 12.349526748971185, 12.554769874476973, 12.609033613445396, 12.766085106382974], "value": 12.65997890295356, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.054367469879526, 11.766764705882345, 11.83733727810652, 12.276257668711693, 12.351882716049367, 12.346666666666689, 11.838994082840243, 12.423105590062129, 12.124242424242425, 12.124090909090901, 12.126999999999967, 12.350308641975309], "value": 12.126999999999967, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.859604743083016, 11.587258687258682, 12.048975903614457, 11.49490421455938, 11.812066929133856, 11.719609375000005, 11.859881422924913, 11.90837301587303, 11.81411417322836, 11.631608527131789, 11.718906250000003, 11.954721115537856], "value": 11.812066929133856, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.176061452513963, 10.755618279569923, 11.494885057471267, 11.173882681564246, 11.2369943820225, 11.240084269662924, 11.301468926553676, 11.178491620111716, 11.365482954545438, 11.11413888888888, 11.365738636363652, 11.430685714285687], "value": 11.240084269662924, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.570250000000001, 6.476750000000001, 6.50995, 6.646699999999999, 6.665749999999999], "value": 6.5783249999999995, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
05:43:29 INFO - Return code: 0
05:43:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
05:43:29 INFO - # TBPL SUCCESS #
05:43:29 INFO - Running post-action listener: _package_coverage_data
05:43:29 INFO - Running post-action listener: _resource_record_post_action
05:43:29 INFO - [mozharness: 2017-10-25 12:43:29.742998Z] Finished run-tests step (success)
05:43:29 INFO - Running post-run listener: _resource_record_post_run
05:43:29 INFO - Total resource usage - Wall time: 602s; CPU: 38.0%; Read bytes: 17367040; Write bytes: 313860096; Read time: 4736; Write time: 363248
05:43:29 INFO - TinderboxPrint: CPU usage
38.1%
05:43:29 INFO - TinderboxPrint: I/O read bytes / time
17,367,040 / 4,736
05:43:29 INFO - TinderboxPrint: I/O write bytes / time
313,860,096 / 363,248
05:43:29 INFO - TinderboxPrint: CPU idle
2,922.0 (61.9%)
05:43:29 INFO - TinderboxPrint: CPU system
303.9 (6.4%)
05:43:29 INFO - TinderboxPrint: CPU user
1,472.9 (31.2%)
05:43:29 INFO - TinderboxPrint: Swap in / out
0 / 0
05:43:29 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 6795264; Read time: 0; Write time: 1944
05:43:29 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
05:43:29 INFO - run-tests - Wall time: 584s; CPU: 39.0%; Read bytes: 15605760; Write bytes: 307064832; Read time: 4692; Write time: 361304
05:43:30 INFO - Running post-run listener: _upload_blobber_files
05:43:30 INFO - Blob upload gear active.
05:43:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
05:43:30 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
05:43:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
05:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
05:43:30 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
05:43:30 INFO - SNIMissingWarning
05:43:30 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
05:43:30 INFO - InsecurePlatformWarning
05:43:30 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
05:43:30 INFO - SubjectAltNameWarning
05:43:30 INFO - (blobuploader) - INFO - Open directory for files ...
05:43:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
05:43:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:43:30 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:43:30 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
05:43:30 INFO - InsecurePlatformWarning
05:43:30 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
05:43:30 INFO - SubjectAltNameWarning
05:43:32 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
05:43:32 INFO - InsecurePlatformWarning
05:43:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
05:43:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:43:32 INFO - (blobuploader) - INFO - Done attempting.
05:43:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
05:43:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:43:32 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:43:33 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
05:43:33 INFO - InsecurePlatformWarning
05:43:33 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.)
05:43:33 INFO - SubjectAltNameWarning
05:43:33 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
05:43:33 INFO - InsecurePlatformWarning
05:43:33 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
05:43:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:43:33 INFO - (blobuploader) - INFO - Done attempting.
05:43:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
05:43:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:43:33 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:43:34 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
05:43:34 INFO - InsecurePlatformWarning
05:43:34 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.)
05:43:34 INFO - SubjectAltNameWarning
05:43:34 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
05:43:34 INFO - InsecurePlatformWarning
05:43:34 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
05:43:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:43:34 INFO - (blobuploader) - INFO - Done attempting.
05:43:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
05:43:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
05:43:34 INFO - (blobuploader) - INFO - Uploading, attempt #1.
05:43:34 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
05:43:34 INFO - InsecurePlatformWarning
05:43:35 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.)
05:43:35 INFO - SubjectAltNameWarning
05:43:35 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
05:43:35 INFO - InsecurePlatformWarning
05:43:35 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
05:43:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
05:43:35 INFO - (blobuploader) - INFO - Done attempting.
05:43:35 INFO - (blobuploader) - INFO - Iteration through files over.
05:43:35 INFO - Return code: 0
05:43:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
05:43:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
05:43:35 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0320f67f99f0f55e60557aeb304c0bacaa7241d32fe551ba2134e2043ecd2b8eefb14b0100813b3c32c3882556515f4362704e3f803a21cd385dc9f4bed85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a71a22446c3f647e8180d55f5d98452a1842f239922c6d57ce8651d646a84e52878509c7b0acd0937a8ff250dd2cf5ba29d3c546e9534ad70999bd1d891e9de", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d65d9874d53552282fe506ac0dac5787b006d24e0f3fe1f2e0c0c3b9f929eabd69ecc35715752e41c8c86b39fb531fe34f5549e456287424952bcbda17d841c9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf49022cad2f758b3e26e2756bd21eaa9f549648900a91c254b7a661c69f3e7af81ff8bc160e5774ae9ae5d7c5df17b8bc2c8afaa2dd260811b2d7ff21ea6510"}
05:43:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
05:43:35 INFO - Writing to file /builds/slave/test/properties/blobber_files
05:43:35 INFO - Contents:
05:43:35 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0320f67f99f0f55e60557aeb304c0bacaa7241d32fe551ba2134e2043ecd2b8eefb14b0100813b3c32c3882556515f4362704e3f803a21cd385dc9f4bed85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a71a22446c3f647e8180d55f5d98452a1842f239922c6d57ce8651d646a84e52878509c7b0acd0937a8ff250dd2cf5ba29d3c546e9534ad70999bd1d891e9de", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d65d9874d53552282fe506ac0dac5787b006d24e0f3fe1f2e0c0c3b9f929eabd69ecc35715752e41c8c86b39fb531fe34f5549e456287424952bcbda17d841c9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf49022cad2f758b3e26e2756bd21eaa9f549648900a91c254b7a661c69f3e7af81ff8bc160e5774ae9ae5d7c5df17b8bc2c8afaa2dd260811b2d7ff21ea6510"}
05:43:35 INFO - Running post-run listener: copy_logs_to_upload_dir
05:43:35 INFO - Copying logs to upload dir...
05:43:35 INFO - mkdir: /builds/slave/test/build/upload/logs
05:43:35 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=664.086222
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 4 secs) (at 2017-10-25 05:43:35.727742) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-25 05:43:35.731666) =========
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=52faae308a556c440401f8e5000001e8-1508934253.220159-468482307
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0320f67f99f0f55e60557aeb304c0bacaa7241d32fe551ba2134e2043ecd2b8eefb14b0100813b3c32c3882556515f4362704e3f803a21cd385dc9f4bed85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a71a22446c3f647e8180d55f5d98452a1842f239922c6d57ce8651d646a84e52878509c7b0acd0937a8ff250dd2cf5ba29d3c546e9534ad70999bd1d891e9de", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d65d9874d53552282fe506ac0dac5787b006d24e0f3fe1f2e0c0c3b9f929eabd69ecc35715752e41c8c86b39fb531fe34f5549e456287424952bcbda17d841c9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf49022cad2f758b3e26e2756bd21eaa9f549648900a91c254b7a661c69f3e7af81ff8bc160e5774ae9ae5d7c5df17b8bc2c8afaa2dd260811b2d7ff21ea6510"}
build_url:https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011228
build_url: 'https://queue.taskcluster.net/v1/task/IObgXzdYS12iK8A9zQpPWQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/21f0320f67f99f0f55e60557aeb304c0bacaa7241d32fe551ba2134e2043ecd2b8eefb14b0100813b3c32c3882556515f4362704e3f803a21cd385dc9f4bed85", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0a71a22446c3f647e8180d55f5d98452a1842f239922c6d57ce8651d646a84e52878509c7b0acd0937a8ff250dd2cf5ba29d3c546e9534ad70999bd1d891e9de", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d65d9874d53552282fe506ac0dac5787b006d24e0f3fe1f2e0c0c3b9f929eabd69ecc35715752e41c8c86b39fb531fe34f5549e456287424952bcbda17d841c9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf49022cad2f758b3e26e2756bd21eaa9f549648900a91c254b7a661c69f3e7af81ff8bc160e5774ae9ae5d7c5df17b8bc2c8afaa2dd260811b2d7ff21ea6510"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-25 05:43:35.764167) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 05:43:35.764551) =========
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=52faae308a556c440401f8e5000001e8-1508934253.220159-468482307
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004954
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-25 05:43:35.846042) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-25 05:43:35.846644) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-25 05:43:35.847010) =========
========= Total master_lag: 0.24 =========