builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-027
starttime: 1509822461.2
results: success (0)
revision: 78568f0b106895cb7573fb1fa355d511ec9c87a3
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.198254) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.198905) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.199192) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.286384) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.286778) =========
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=1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-04 12:07:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 77.0M=0s
2017-11-04 12:07:41 (77.0 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.134544
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.449320) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.449625) =========
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=1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.022448
========= master_lag: 0.06 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:41.529996) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-04 12:07:41.530319) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 78568f0b106895cb7573fb1fa355d511ec9c87a3 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 78568f0b106895cb7573fb1fa355d511ec9c87a3 --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=1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-04 12:07:41,951 truncating revision to first 12 chars
2017-11-04 12:07:41,952 Setting DEBUG logging.
2017-11-04 12:07:41,952 attempt 1/10
2017-11-04 12:07:41,952 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/78568f0b1068?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-04 12:07:42,379 unpacking tar archive at: mozilla-beta-78568f0b1068/testing/mozharness/
program finished with exit code 0
elapsedTime=1.162058
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-04 12:07:42.721193) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:42.721707) =========
script_repo_revision: 78568f0b106895cb7573fb1fa355d511ec9c87a3
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:42.722260) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:42.726450) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-04 12:07:42.791520) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 43 secs) (at 2017-11-04 12:07:42.791948) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
12:07:43 INFO - MultiFileLogger online at 20171104 12:07:43 in /builds/slave/test
12:07:43 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
12:07:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
12:07:43 INFO - {'append_to_log': False,
12:07:43 INFO - 'base_work_dir': '/builds/slave/test',
12:07:43 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
12:07:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
12:07:43 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
12:07:43 INFO - 'buildbot_json_path': 'buildprops.json',
12:07:43 INFO - 'code_coverage': False,
12:07:43 INFO - 'config_files': ('talos/linux_config.py',),
12:07:43 INFO - 'default_actions': ('clobber',
12:07:43 INFO - 'read-buildbot-config',
12:07:43 INFO - 'download-and-extract',
12:07:43 INFO - 'populate-webroot',
12:07:43 INFO - 'create-virtualenv',
12:07:43 INFO - 'install',
12:07:43 INFO - 'setup-mitmproxy',
12:07:43 INFO - 'run-tests'),
12:07:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:07:43 INFO - 'disable_ccov_upload': False,
12:07:43 INFO - 'disable_stylo': False,
12:07:43 INFO - 'download_minidump_stackwalk': True,
12:07:43 INFO - 'download_symbols': 'ondemand',
12:07:43 INFO - 'e10s': False,
12:07:43 INFO - 'enable_stylo': False,
12:07:43 INFO - 'enable_webrender': False,
12:07:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
12:07:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:07:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:07:43 INFO - 'gecko_profile': False,
12:07:43 INFO - 'gecko_profile_interval': 0,
12:07:43 INFO - 'installer_path': 'installer.exe',
12:07:43 INFO - 'jsd_code_coverage': False,
12:07:43 INFO - 'log_level': 'info',
12:07:43 INFO - 'log_name': 'talos',
12:07:43 INFO - 'log_to_console': True,
12:07:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
12:07:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
12:07:43 INFO - 'opt_config_files': (),
12:07:43 INFO - 'pip_index': False,
12:07:43 INFO - 'suite': 'g4-e10s',
12:07:43 INFO - 'system_bits': '32',
12:07:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:07:43 INFO - 'title': 'talos-linux64-ix-027',
12:07:43 INFO - 'tooltool_cache': '/builds/tooltool_cache',
12:07:43 INFO - 'use_talos_json': True,
12:07:43 INFO - 'verify': 'False',
12:07:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
12:07:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:07:43 INFO - 'work_dir': 'build'}
12:07:43 INFO - [mozharness: 2017-11-04 19:07:43.338095Z] Running clobber step.
12:07:43 INFO - Running pre-action listener: _resource_record_pre_action
12:07:43 INFO - Running main action method: clobber
12:07:43 INFO - rmtree: /builds/slave/test/build
12:07:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
12:07:44 INFO - Running post-action listener: _resource_record_post_action
12:07:44 INFO - [mozharness: 2017-11-04 19:07:44.947000Z] Finished clobber step (success)
12:07:44 INFO - [mozharness: 2017-11-04 19:07:44.947096Z] Running read-buildbot-config step.
12:07:44 INFO - Running pre-action listener: _resource_record_pre_action
12:07:44 INFO - Running main action method: read_buildbot_config
12:07:44 INFO - Using buildbot properties:
12:07:44 INFO - {
12:07:44 INFO - "project": "",
12:07:44 INFO - "product": "firefox",
12:07:44 INFO - "who": "ryanvm@gmail.com",
12:07:44 INFO - "installer_path": "public/build/target.tar.bz2",
12:07:44 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
12:07:44 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
12:07:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
12:07:44 INFO - "stage_platform": "linux64",
12:07:44 INFO - "basedir": "/builds/slave/test",
12:07:44 INFO - "buildnumber": 252,
12:07:44 INFO - "platform": "ubuntu64_hw",
12:07:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
12:07:44 INFO - "slavebuilddir": "test",
12:07:44 INFO - "taskId": "PVsUk6hLTi6c6K1n4O0gug",
12:07:44 INFO - "branch": "mozilla-beta",
12:07:44 INFO - "script_repo_revision": "production",
12:07:44 INFO - "revision": "78568f0b106895cb7573fb1fa355d511ec9c87a3",
12:07:44 INFO - "slavename": "talos-linux64-ix-027",
12:07:44 INFO - "repo_path": "releases/mozilla-beta"
12:07:44 INFO - }
12:07:44 INFO - Finding installer, test and symbols from parent task.
12:07:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PVsUk6hLTi6c6K1n4O0gug'}, attempt #1
12:07:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PVsUk6hLTi6c6K1n4O0gug'}, attempt #1
12:07:45 INFO - Task dependencies: Whhu0yUYRLuESDbWfXiKXQ
12:07:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ'}, attempt #1
12:07:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2
12:07:46 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.test_packages.json
12:07:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.crashreporter-symbols.zip
12:07:46 INFO - Running post-action listener: _resource_record_post_action
12:07:46 INFO - [mozharness: 2017-11-04 19:07:46.301210Z] Finished read-buildbot-config step (success)
12:07:46 INFO - [mozharness: 2017-11-04 19:07:46.301469Z] Running download-and-extract step.
12:07:46 INFO - Running pre-action listener: _resource_record_pre_action
12:07:46 INFO - Running main action method: download_and_extract
12:07:46 INFO - mkdir: /builds/slave/test/build/tests
12:07: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')]}
12:07:46 INFO - trying https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.test_packages.json
12:07:46 INFO - Downloading https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
12:07:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
12:07:46 INFO - Downloaded 989 bytes.
12:07:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
12:07:46 INFO - Using the following test package requirements:
12:07:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
12:07:46 INFO - u'common': [u'target.common.tests.zip'],
12:07:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
12:07:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
12:07:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
12:07:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
12:07:46 INFO - u'mozbase': [u'target.common.tests.zip'],
12:07:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
12:07:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
12:07:46 INFO - u'web-platform': [u'target.common.tests.zip',
12:07:46 INFO - u'target.web-platform.tests.tar.gz'],
12:07:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
12:07:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
12:07:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.common.tests.zip
12:07:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
12:07:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.common.tests.zip into memory
12:07:49 INFO - Content-Length response header: 58441179
12:07:49 INFO - Bytes received: 58441179
12:07:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.talos.tests.zip
12:07:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
12:07:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.talos.tests.zip into memory
12:08:01 INFO - Content-Length response header: 18771919
12:08:01 INFO - Bytes received: 18771919
12:08: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')]}
12:08:02 INFO - trying https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2
12:08:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
12:08:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
12:08:04 INFO - Downloaded 54188146 bytes.
12:08:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2
12:08:04 INFO - mkdir: /builds/slave/test/properties
12:08:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
12:08:04 INFO - Writing to file /builds/slave/test/properties/build_url
12:08:04 INFO - Contents:
12:08:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2
12:08:04 INFO - Running post-action listener: _resource_record_post_action
12:08:04 INFO - Running post-action listener: find_tests_for_verification
12:08:04 INFO - Running post-action listener: set_extra_try_arguments
12:08:04 INFO - [mozharness: 2017-11-04 19:08:04.721880Z] Finished download-and-extract step (success)
12:08:04 INFO - [mozharness: 2017-11-04 19:08:04.721987Z] Running populate-webroot step.
12:08:04 INFO - Running pre-action listener: _resource_record_pre_action
12:08:04 INFO - Running main action method: populate_webroot
12:08:04 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
12:08:04 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['tresize',
12:08:04 INFO - 'tcanvasmark']},
12:08:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
12:08:04 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['dromaeo_css',
12:08:04 INFO - 'kraken']},
12:08:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:08:04 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:08:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'tests': ['damp', 'tps']},
12:08:04 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['damp', 'tps']},
12:08:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
12:08:04 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['dromaeo_dom']},
12:08:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
12:08:04 INFO - 'glvideo',
12:08:04 INFO - 'displaylist_mutate']},
12:08:04 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['basic_compositor_video',
12:08:04 INFO - 'glvideo']},
12:08:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
12:08:04 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['ts_paint_webext',
12:08:04 INFO - 'tp5o_webext']},
12:08:04 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
12:08:04 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:08:04 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:08:04 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:08:04 INFO - 'talos_options': ['--mitmproxy',
12:08:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:08:04 INFO - '--firstNonBlankPaint'],
12:08:04 INFO - 'tests': ['tp6_google_heavy',
12:08:04 INFO - 'tp6_youtube_heavy',
12:08:04 INFO - 'tp6_amazon_heavy',
12:08:04 INFO - 'tp6_facebook_heavy']},
12:08:04 INFO - 'other-e10s': {'tests': ['a11yr',
12:08:04 INFO - 'ts_paint',
12:08:04 INFO - 'tpaint',
12:08:04 INFO - 'sessionrestore',
12:08:04 INFO - 'sessionrestore_many_windows',
12:08:04 INFO - 'sessionrestore_no_auto_restore',
12:08:04 INFO - 'tabpaint',
12:08:04 INFO - 'cpstartup']},
12:08:04 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['a11yr',
12:08:04 INFO - 'ts_paint',
12:08:04 INFO - 'tpaint',
12:08:04 INFO - 'sessionrestore',
12:08:04 INFO - 'sessionrestore_many_windows',
12:08:04 INFO - 'sessionrestore_no_auto_restore',
12:08:04 INFO - 'tabpaint',
12:08:04 INFO - 'cpstartup']},
12:08:04 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
12:08:04 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
12:08:04 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['perf_reftest_singletons']},
12:08:04 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['perf_reftest']},
12:08:04 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
12:08:04 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['speedometer']},
12:08:04 INFO - 'svgr-e10s': {'tests': ['tsvgx',
12:08:04 INFO - 'tsvgr_opacity',
12:08:04 INFO - 'tart',
12:08:04 INFO - 'tscrollx',
12:08:04 INFO - 'tsvg_static']},
12:08:04 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['tsvgx',
12:08:04 INFO - 'tsvgr_opacity',
12:08:04 INFO - 'tart',
12:08:04 INFO - 'tscrollx',
12:08:04 INFO - 'tsvg_static']},
12:08:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
12:08:04 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'talos_options': ['--disable-stylo'],
12:08:04 INFO - 'tests': ['tp5o']},
12:08:04 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:08:04 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:08:04 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:08:04 INFO - 'talos_options': ['--mitmproxy',
12:08:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:08:04 INFO - '--firstNonBlankPaint'],
12:08:04 INFO - 'tests': ['tp6_google',
12:08:04 INFO - 'tp6_youtube',
12:08:04 INFO - 'tp6_amazon',
12:08:04 INFO - 'tp6_facebook']},
12:08:04 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:08:04 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:08:04 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:08:04 INFO - 'talos_options': ['--disable-stylo',
12:08:04 INFO - '--mitmproxy',
12:08:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:08:04 INFO - '--firstNonBlankPaint'],
12:08:04 INFO - 'tests': ['tp6_google',
12:08:04 INFO - 'tp6_youtube',
12:08:04 INFO - 'tp6_amazon',
12:08:04 INFO - 'tp6_facebook']},
12:08:04 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:08:04 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:08:04 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:08:04 INFO - 'talos_options': ['--stylo-threads=1',
12:08:04 INFO - '--mitmproxy',
12:08:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:08:04 INFO - '--firstNonBlankPaint'],
12:08:04 INFO - 'tests': ['tp6_google',
12:08:04 INFO - 'tp6_youtube',
12:08:04 INFO - 'tp6_amazon',
12:08:04 INFO - 'tp6_facebook']},
12:08:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'talos_options': ['--xperf_path',
12:08:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:08:04 INFO - 'tests': ['tp5n']},
12:08:04 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:08:04 INFO - 'talos_options': ['--disable-stylo',
12:08:04 INFO - '--xperf_path',
12:08:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:08:04 INFO - 'tests': ['tp5n']}}}
12:08:04 INFO - Running post-action listener: _resource_record_post_action
12:08:04 INFO - [mozharness: 2017-11-04 19:08:04.742267Z] Finished populate-webroot step (success)
12:08:04 INFO - [mozharness: 2017-11-04 19:08:04.742378Z] Running create-virtualenv step.
12:08:04 INFO - Running pre-action listener: _resource_record_pre_action
12:08:04 INFO - Running main action method: create_virtualenv
12:08:04 INFO - Creating virtualenv /builds/slave/test/build/venv
12:08:04 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
12:08:04 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
12:08:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
12:08:05 INFO - Using real prefix '/usr'
12:08:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python
12:08:05 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
12:08:06 INFO - Installing setuptools, pip, wheel...done.
12:08:06 INFO - Return code: 0
12:08:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
12:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:06 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
12:08:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
12:08:06 INFO - Using env: {'DISPLAY': ':0',
12:08:06 INFO - 'HOME': '/home/cltbld',
12:08:06 INFO - 'LANG': 'en_US.UTF-8',
12:08:06 INFO - 'LANGUAGE': 'en_US:en',
12:08:06 INFO - 'LOGNAME': 'cltbld',
12:08:06 INFO - 'MAIL': '/var/mail/cltbld',
12:08:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:06 INFO - 'MOZ_NO_REMOTE': '1',
12:08:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:06 INFO - 'NO_EM_RESTART': '1',
12:08:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:06 INFO - 'PWD': '/builds/slave/test',
12:08:06 INFO - 'SHELL': '/bin/bash',
12:08:06 INFO - 'SHLVL': '1',
12:08:06 INFO - 'TERM': 'linux',
12:08:06 INFO - 'TMOUT': '86400',
12:08:06 INFO - 'USER': 'cltbld',
12:08:06 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:06 INFO - '_': '/tools/buildbot/bin/python'}
12:08:07 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
12:08:07 INFO - Return code: 0
12:08:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
12:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:07 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
12:08:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
12:08:07 INFO - Using env: {'DISPLAY': ':0',
12:08:07 INFO - 'HOME': '/home/cltbld',
12:08:07 INFO - 'LANG': 'en_US.UTF-8',
12:08:07 INFO - 'LANGUAGE': 'en_US:en',
12:08:07 INFO - 'LOGNAME': 'cltbld',
12:08:07 INFO - 'MAIL': '/var/mail/cltbld',
12:08:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:07 INFO - 'MOZ_NO_REMOTE': '1',
12:08:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:07 INFO - 'NO_EM_RESTART': '1',
12:08:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:07 INFO - 'PWD': '/builds/slave/test',
12:08:07 INFO - 'SHELL': '/bin/bash',
12:08:07 INFO - 'SHLVL': '1',
12:08:07 INFO - 'TERM': 'linux',
12:08:07 INFO - 'TMOUT': '86400',
12:08:07 INFO - 'USER': 'cltbld',
12:08:07 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:07 INFO - '_': '/tools/buildbot/bin/python'}
12:08:07 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:07 INFO - Collecting psutil>=3.1.1
12:08:08 INFO - Installing collected packages: psutil
12:08:08 INFO - Successfully installed psutil-3.1.1
12:08:08 INFO - Return code: 0
12:08:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
12:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:08 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
12:08:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
12:08:08 INFO - Using env: {'DISPLAY': ':0',
12:08:08 INFO - 'HOME': '/home/cltbld',
12:08:08 INFO - 'LANG': 'en_US.UTF-8',
12:08:08 INFO - 'LANGUAGE': 'en_US:en',
12:08:08 INFO - 'LOGNAME': 'cltbld',
12:08:08 INFO - 'MAIL': '/var/mail/cltbld',
12:08:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:08 INFO - 'MOZ_NO_REMOTE': '1',
12:08:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:08 INFO - 'NO_EM_RESTART': '1',
12:08:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:08 INFO - 'PWD': '/builds/slave/test',
12:08:08 INFO - 'SHELL': '/bin/bash',
12:08:08 INFO - 'SHLVL': '1',
12:08:08 INFO - 'TERM': 'linux',
12:08:08 INFO - 'TMOUT': '86400',
12:08:08 INFO - 'USER': 'cltbld',
12:08:08 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:08 INFO - '_': '/tools/buildbot/bin/python'}
12:08:09 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:09 INFO - Collecting mozsystemmonitor==0.3
12:08:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
12:08:10 INFO - Installing collected packages: mozsystemmonitor
12:08:10 INFO - Successfully installed mozsystemmonitor-0.3
12:08:10 INFO - Return code: 0
12:08:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
12:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:10 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
12:08:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
12:08:10 INFO - Using env: {'DISPLAY': ':0',
12:08:10 INFO - 'HOME': '/home/cltbld',
12:08:10 INFO - 'LANG': 'en_US.UTF-8',
12:08:10 INFO - 'LANGUAGE': 'en_US:en',
12:08:10 INFO - 'LOGNAME': 'cltbld',
12:08:10 INFO - 'MAIL': '/var/mail/cltbld',
12:08:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:10 INFO - 'MOZ_NO_REMOTE': '1',
12:08:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:10 INFO - 'NO_EM_RESTART': '1',
12:08:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:10 INFO - 'PWD': '/builds/slave/test',
12:08:10 INFO - 'SHELL': '/bin/bash',
12:08:10 INFO - 'SHLVL': '1',
12:08:10 INFO - 'TERM': 'linux',
12:08:10 INFO - 'TMOUT': '86400',
12:08:10 INFO - 'USER': 'cltbld',
12:08:10 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:10 INFO - '_': '/tools/buildbot/bin/python'}
12:08:10 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:10 INFO - Collecting jsonschema==2.5.1
12:08:11 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
12:08:11 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
12:08:12 INFO - Installing collected packages: functools32, jsonschema
12:08:13 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
12:08:13 INFO - Return code: 0
12:08:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
12:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:13 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
12:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
12:08:13 INFO - Using env: {'DISPLAY': ':0',
12:08:13 INFO - 'HOME': '/home/cltbld',
12:08:13 INFO - 'LANG': 'en_US.UTF-8',
12:08:13 INFO - 'LANGUAGE': 'en_US:en',
12:08:13 INFO - 'LOGNAME': 'cltbld',
12:08:13 INFO - 'MAIL': '/var/mail/cltbld',
12:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:13 INFO - 'MOZ_NO_REMOTE': '1',
12:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:13 INFO - 'NO_EM_RESTART': '1',
12:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:13 INFO - 'PWD': '/builds/slave/test',
12:08:13 INFO - 'SHELL': '/bin/bash',
12:08:13 INFO - 'SHLVL': '1',
12:08:13 INFO - 'TERM': 'linux',
12:08:13 INFO - 'TMOUT': '86400',
12:08:13 INFO - 'USER': 'cltbld',
12:08:13 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:13 INFO - '_': '/tools/buildbot/bin/python'}
12:08:13 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
12:08:13 INFO - Return code: 0
12:08:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
12:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:13 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
12:08:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
12:08:13 INFO - Using env: {'DISPLAY': ':0',
12:08:13 INFO - 'HOME': '/home/cltbld',
12:08:13 INFO - 'LANG': 'en_US.UTF-8',
12:08:13 INFO - 'LANGUAGE': 'en_US:en',
12:08:13 INFO - 'LOGNAME': 'cltbld',
12:08:13 INFO - 'MAIL': '/var/mail/cltbld',
12:08:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:13 INFO - 'MOZ_NO_REMOTE': '1',
12:08:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:13 INFO - 'NO_EM_RESTART': '1',
12:08:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:13 INFO - 'PWD': '/builds/slave/test',
12:08:13 INFO - 'SHELL': '/bin/bash',
12:08:13 INFO - 'SHLVL': '1',
12:08:13 INFO - 'TERM': 'linux',
12:08:13 INFO - 'TMOUT': '86400',
12:08:13 INFO - 'USER': 'cltbld',
12:08:13 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:13 INFO - '_': '/tools/buildbot/bin/python'}
12:08:14 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:14 INFO - Collecting blobuploader==1.2.4
12:08:15 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
12:08:16 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
12:08:17 INFO - Installing collected packages: requests, docopt, blobuploader
12:08:17 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
12:08:17 INFO - Return code: 0
12:08:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:17 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
12:08:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:08:17 INFO - Using env: {'DISPLAY': ':0',
12:08:17 INFO - 'HOME': '/home/cltbld',
12:08:17 INFO - 'LANG': 'en_US.UTF-8',
12:08:17 INFO - 'LANGUAGE': 'en_US:en',
12:08:17 INFO - 'LOGNAME': 'cltbld',
12:08:17 INFO - 'MAIL': '/var/mail/cltbld',
12:08:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:17 INFO - 'MOZ_NO_REMOTE': '1',
12:08:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:17 INFO - 'NO_EM_RESTART': '1',
12:08:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:17 INFO - 'PWD': '/builds/slave/test',
12:08:17 INFO - 'SHELL': '/bin/bash',
12:08:17 INFO - 'SHLVL': '1',
12:08:17 INFO - 'TERM': 'linux',
12:08:17 INFO - 'TMOUT': '86400',
12:08:17 INFO - 'USER': 'cltbld',
12:08:17 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:17 INFO - '_': '/tools/buildbot/bin/python'}
12:08:18 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:18 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:08:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:08:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:08:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:08:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:08:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:08:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:08:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:08:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:08:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:08:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:08:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:08:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:08:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:08:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:08:20 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:08:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:08:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:08:21 INFO - Running setup.py install for manifestparser: started
12:08:21 INFO - Running setup.py install for manifestparser: finished with status 'done'
12:08:21 INFO - Running setup.py install for mozcrash: started
12:08:21 INFO - Running setup.py install for mozcrash: finished with status 'done'
12:08:21 INFO - Running setup.py install for mozdebug: started
12:08:21 INFO - Running setup.py install for mozdebug: finished with status 'done'
12:08:21 INFO - Running setup.py install for mozdevice: started
12:08:22 INFO - Running setup.py install for mozdevice: finished with status 'done'
12:08:22 INFO - Running setup.py install for mozfile: started
12:08:22 INFO - Running setup.py install for mozfile: finished with status 'done'
12:08:22 INFO - Running setup.py install for mozhttpd: started
12:08:22 INFO - Running setup.py install for mozhttpd: finished with status 'done'
12:08:22 INFO - Running setup.py install for mozinfo: started
12:08:22 INFO - Running setup.py install for mozinfo: finished with status 'done'
12:08:22 INFO - Running setup.py install for mozInstall: started
12:08:22 INFO - Running setup.py install for mozInstall: finished with status 'done'
12:08:22 INFO - Running setup.py install for mozleak: started
12:08:23 INFO - Running setup.py install for mozleak: finished with status 'done'
12:08:23 INFO - Running setup.py install for mozlog: started
12:08:23 INFO - Running setup.py install for mozlog: finished with status 'done'
12:08:23 INFO - Running setup.py install for moznetwork: started
12:08:23 INFO - Running setup.py install for moznetwork: finished with status 'done'
12:08:23 INFO - Running setup.py install for mozprocess: started
12:08:23 INFO - Running setup.py install for mozprocess: finished with status 'done'
12:08:23 INFO - Running setup.py install for mozprofile: started
12:08:24 INFO - Running setup.py install for mozprofile: finished with status 'done'
12:08:24 INFO - Running setup.py install for mozrunner: started
12:08:24 INFO - Running setup.py install for mozrunner: finished with status 'done'
12:08:24 INFO - Running setup.py install for mozscreenshot: started
12:08:24 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
12:08:24 INFO - Running setup.py install for moztest: started
12:08:24 INFO - Running setup.py install for moztest: finished with status 'done'
12:08:24 INFO - Running setup.py install for mozversion: started
12:08:25 INFO - Running setup.py install for mozversion: finished with status 'done'
12:08:25 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
12:08:25 INFO - Return code: 0
12:08:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:25 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
12:08:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:08:25 INFO - Using env: {'DISPLAY': ':0',
12:08:25 INFO - 'HOME': '/home/cltbld',
12:08:25 INFO - 'LANG': 'en_US.UTF-8',
12:08:25 INFO - 'LANGUAGE': 'en_US:en',
12:08:25 INFO - 'LOGNAME': 'cltbld',
12:08:25 INFO - 'MAIL': '/var/mail/cltbld',
12:08:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:25 INFO - 'MOZ_NO_REMOTE': '1',
12:08:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:25 INFO - 'NO_EM_RESTART': '1',
12:08:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:25 INFO - 'PWD': '/builds/slave/test',
12:08:25 INFO - 'SHELL': '/bin/bash',
12:08:25 INFO - 'SHLVL': '1',
12:08:25 INFO - 'TERM': 'linux',
12:08:25 INFO - 'TMOUT': '86400',
12:08:25 INFO - 'USER': 'cltbld',
12:08:25 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:25 INFO - '_': '/tools/buildbot/bin/python'}
12:08:25 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:25 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:08:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:08:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
12:08:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:08:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
12:08:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:08:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
12:08:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
12:08:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:08:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
12:08:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:08:27 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:08:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
12:08:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
12:08:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
12:08:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
12:08:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
12:08:28 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:08:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
12:08:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:08:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
12:08:29 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:08:30 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
12:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
12:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:08:30 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:08:31 INFO - Installing collected packages: six, blessings
12:08:31 INFO - Successfully installed blessings-1.6 six-1.10.0
12:08:31 INFO - Return code: 0
12:08:31 INFO - Done creating virtualenv /builds/slave/test/build/venv.
12:08:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:08:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:08:31 INFO - Reading from file tmpfile_stdout
12:08:31 INFO - Reading from file tmpfile_stderr
12:08:31 INFO - Current package versions:
12:08:31 INFO - blessings == 1.6
12:08:31 INFO - blobuploader == 1.2.4
12:08:31 INFO - docopt == 0.6.1
12:08:31 INFO - functools32 == 3.2.3.post2
12:08:31 INFO - jsonschema == 2.5.1
12:08:31 INFO - manifestparser == 1.2
12:08:31 INFO - mozInstall == 1.14
12:08:31 INFO - mozcrash == 1.0
12:08:31 INFO - mozdebug == 0.1
12:08:31 INFO - mozdevice == 0.51
12:08:31 INFO - mozfile == 1.2
12:08:31 INFO - mozhttpd == 0.7
12:08:31 INFO - mozinfo == 0.10
12:08:31 INFO - mozleak == 0.1
12:08:31 INFO - mozlog == 3.5
12:08:31 INFO - moznetwork == 0.27
12:08:31 INFO - mozprocess == 0.25
12:08:31 INFO - mozprofile == 0.29
12:08:31 INFO - mozrunner == 6.13
12:08:31 INFO - mozscreenshot == 0.1
12:08:31 INFO - mozsystemmonitor == 0.3
12:08:31 INFO - moztest == 0.8
12:08:31 INFO - mozversion == 1.4
12:08:31 INFO - psutil == 3.1.1
12:08:31 INFO - requests == 1.2.3
12:08:31 INFO - six == 1.10.0
12:08:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:32 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 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
12:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:08:32 INFO - Using env: {'DISPLAY': ':0',
12:08:32 INFO - 'HOME': '/home/cltbld',
12:08:32 INFO - 'LANG': 'en_US.UTF-8',
12:08:32 INFO - 'LANGUAGE': 'en_US:en',
12:08:32 INFO - 'LOGNAME': 'cltbld',
12:08:32 INFO - 'MAIL': '/var/mail/cltbld',
12:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:32 INFO - 'MOZ_NO_REMOTE': '1',
12:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:32 INFO - 'NO_EM_RESTART': '1',
12:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:32 INFO - 'PWD': '/builds/slave/test',
12:08:32 INFO - 'SHELL': '/bin/bash',
12:08:32 INFO - 'SHLVL': '1',
12:08:32 INFO - 'TERM': 'linux',
12:08:32 INFO - 'TMOUT': '86400',
12:08:32 INFO - 'USER': 'cltbld',
12:08:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:32 INFO - '_': '/tools/buildbot/bin/python'}
12:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
12:08:32 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
12:08:33 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:08:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
12:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:08:34 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:08:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
12:08:35 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:08:36 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
12:08:36 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:08:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
12:08:37 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:08:38 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
12:08:38 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
12:08:39 INFO - Found existing installation: requests 1.2.3
12:08:39 INFO - Uninstalling requests-1.2.3:
12:08:39 INFO - Successfully uninstalled requests-1.2.3
12:08:39 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
12:08:39 INFO - Return code: 0
12:08:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
12:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:08:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:08:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x21692d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2341a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24e4930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, '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': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:08:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
12:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
12:08:39 INFO - Using env: {'DISPLAY': ':0',
12:08:39 INFO - 'HOME': '/home/cltbld',
12:08:39 INFO - 'LANG': 'en_US.UTF-8',
12:08:39 INFO - 'LANGUAGE': 'en_US:en',
12:08:39 INFO - 'LOGNAME': 'cltbld',
12:08:39 INFO - 'MAIL': '/var/mail/cltbld',
12:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:39 INFO - 'MOZ_NO_REMOTE': '1',
12:08:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:39 INFO - 'NO_EM_RESTART': '1',
12:08:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:39 INFO - 'PWD': '/builds/slave/test',
12:08:39 INFO - 'SHELL': '/bin/bash',
12:08:39 INFO - 'SHLVL': '1',
12:08:39 INFO - 'TERM': 'linux',
12:08:39 INFO - 'TMOUT': '86400',
12:08:39 INFO - 'USER': 'cltbld',
12:08:39 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:39 INFO - '_': '/tools/buildbot/bin/python'}
12:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple
12:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
12:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
12:08:39 INFO - Return code: 0
12:08:39 INFO - Running post-action listener: _resource_record_post_action
12:08:39 INFO - Running post-action listener: _start_resource_monitoring
12:08:39 INFO - Starting resource monitoring.
12:08:39 INFO - [mozharness: 2017-11-04 19:08:39.817194Z] Finished create-virtualenv step (success)
12:08:39 INFO - [mozharness: 2017-11-04 19:08:39.817860Z] Running install step.
12:08:39 INFO - Running pre-action listener: _resource_record_pre_action
12:08:39 INFO - Running main action method: install
12:08:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:08:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:08:40 INFO - Reading from file tmpfile_stdout
12:08:40 INFO - Reading from file tmpfile_stderr
12:08:40 INFO - Detecting whether we're running mozinstall >=1.0...
12:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
12:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
12:08:40 INFO - Reading from file tmpfile_stdout
12:08:40 INFO - Output received:
12:08:40 INFO - Usage: mozinstall [options] installer
12:08:40 INFO - Options:
12:08:40 INFO - -h, --help show this help message and exit
12:08:40 INFO - -d DEST, --destination=DEST
12:08:40 INFO - Directory to install application into. [default:
12:08:40 INFO - "/builds/slave/test"]
12:08:40 INFO - --app=APP Application being installed. [default: firefox]
12:08:40 INFO - mkdir: /builds/slave/test/build/application
12:08:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
12:08:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
12:08:55 INFO - Reading from file tmpfile_stdout
12:08:55 INFO - Output received:
12:08:55 INFO - /builds/slave/test/build/application/firefox/firefox
12:08:55 INFO - Running post-action listener: _resource_record_post_action
12:08:55 INFO - [mozharness: 2017-11-04 19:08:55.454409Z] Finished install step (success)
12:08:55 INFO - [mozharness: 2017-11-04 19:08:55.454654Z] Running setup-mitmproxy step.
12:08:55 INFO - Running pre-action listener: _resource_record_pre_action
12:08:55 INFO - Running main action method: setup_mitmproxy
12:08:55 INFO - Skipping: mitmproxy is not required
12:08:55 INFO - Running post-action listener: _resource_record_post_action
12:08:55 INFO - [mozharness: 2017-11-04 19:08:55.455753Z] Finished setup-mitmproxy step (success)
12:08:55 INFO - [mozharness: 2017-11-04 19:08:55.455969Z] Running run-tests step.
12:08:55 INFO - Running pre-action listener: _resource_record_pre_action
12:08:55 INFO - Running pre-action listener: _set_gcov_prefix
12:08:55 INFO - Running main action method: run_tests
12:08:55 WARNING - Try message not found.
12:08:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
12:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
12:08:55 INFO - Python 2.7.3
12:08:55 INFO - Return code: 0
12:08:55 INFO - grabbing minidump binary from tooltool
12:08:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:08:55 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 0x24e1ad0>, '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 0x24e29d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24e2e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
12:08:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
12:08:55 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
12:08:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
12:08:55 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
12:08:55 INFO - Return code: 0
12:08:55 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
12:08:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
12:08:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
12:08:55 INFO - ENV: RUST_BACKTRACE is now full
12:08:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
12:08:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
12:08:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
12:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-027 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
12:08:55 INFO - Using env: {'DISPLAY': ':0',
12:08:55 INFO - 'HOME': '/home/cltbld',
12:08:55 INFO - 'LANG': 'en_US.UTF-8',
12:08:55 INFO - 'LANGUAGE': 'en_US:en',
12:08:55 INFO - 'LOGNAME': 'cltbld',
12:08:55 INFO - 'MAIL': '/var/mail/cltbld',
12:08:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:08:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:08:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:08:55 INFO - 'MOZ_NO_REMOTE': '1',
12:08:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:08:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:08:55 INFO - 'NO_EM_RESTART': '1',
12:08:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:08:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:08:55 INFO - 'PWD': '/builds/slave/test',
12:08:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:08:55 INFO - 'RUST_BACKTRACE': 'full',
12:08:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:08:55 INFO - 'SHELL': '/bin/bash',
12:08:55 INFO - 'SHLVL': '1',
12:08:55 INFO - 'TERM': 'linux',
12:08:55 INFO - 'TMOUT': '86400',
12:08:55 INFO - 'USER': 'cltbld',
12:08:55 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:08:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:08:55 INFO - '_': '/tools/buildbot/bin/python'}
12:08:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
12:08:55 INFO - mozversion application_buildid: 20171104174529
12:08:55 INFO - mozversion application_changeset: 78568f0b106895cb7573fb1fa355d511ec9c87a3
12:08:55 INFO - mozversion application_display_name: Firefox
12:08:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:08:55 INFO - mozversion application_name: Firefox
12:08:55 INFO - mozversion application_remotingname: firefox
12:08:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
12:08:55 INFO - mozversion application_vendor: Mozilla
12:08:55 INFO - mozversion application_version: 58.0
12:08:55 INFO - mozversion platform_buildid: 20171104174529
12:08:55 INFO - mozversion platform_changeset: 78568f0b106895cb7573fb1fa355d511ec9c87a3
12:08:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
12:08:55 INFO - mozversion platform_version: 58.0
12:08:55 INFO - using testdate: 1509822535
12:08:55 INFO - actual date: 1509822535
12:08:55 INFO - starting webserver on 'localhost:56431'
12:08:55 INFO - SUITE-START | Running 3 tests
12:08:55 INFO - TEST-START | basic_compositor_video
12:08:55 INFO - Initialising browser for basic_compositor_video test...
12:08:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56431/getInfo.html -profile /tmp/tmpH_Tio0/profile
12:08:55 INFO - TEST-INFO | started process 16582 (/builds/slave/test/build/application/firefox/firefox http://localhost:56431/getInfo.html)
12:09:01 INFO - TEST-INFO | 16582: exit 0
12:09:01 INFO - Browser initialized.
12:09:01 INFO - Running cycle 1/1 for basic_compositor_video test...
12:09:01 INFO - Using env: {'DISPLAY': ':0',
12:09:01 INFO - 'HOME': '/home/cltbld',
12:09:01 INFO - 'JSGC_DISABLE_POISONING': '1',
12:09:01 INFO - 'LANG': 'en_US.UTF-8',
12:09:01 INFO - 'LANGUAGE': 'en_US:en',
12:09:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:09:01 INFO - 'LOGNAME': 'cltbld',
12:09:01 INFO - 'MAIL': '/var/mail/cltbld',
12:09:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:09:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:09:01 INFO - 'MOZ_CRASHREPORTER': '1',
12:09:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:09:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:09:01 INFO - 'MOZ_NO_REMOTE': '1',
12:09:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:09:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:09:01 INFO - 'NO_EM_RESTART': '1',
12:09:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:09:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:09:01 INFO - 'PWD': '/builds/slave/test',
12:09:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:09:01 INFO - 'RUST_BACKTRACE': 'full',
12:09:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:09:01 INFO - 'SHELL': '/bin/bash',
12:09:01 INFO - 'SHLVL': '1',
12:09:01 INFO - 'TERM': 'linux',
12:09:01 INFO - 'TMOUT': '86400',
12:09:01 INFO - 'USER': 'cltbld',
12:09:01 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:09:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:09:01 INFO - '_': '/tools/buildbot/bin/python'}
12:09:01 INFO - TEST-INFO | started process 16857 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH_Tio0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
12:09:01 INFO - PID 16857 |
12:09:01 INFO - PID 16857 | (/builds/slave/test/build/application/firefox/firefox:16907): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:09:01 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6798460246360585 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6667333333333332 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.6982031692133557 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6952627118644075 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.016405622489959 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.16846875 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.476732329084591 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.414795221843003 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.527090239410686 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589427374301675 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 9.870016447368423 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 10.204413265306115 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.197388059701497 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.70048128342245 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500104166666672 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977275449101807 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.407585551330797 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812918918918916 ms/frame
12:09:48 INFO - PID 16857 |
12:09:48 INFO - PID 16857 | Cycle 1(1): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:10:34 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800419932810753 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683277731442867 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7637595532039976 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7038245315161846 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 3.7086711990111234 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9615148514851497 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.5380483490566053 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5043520140105024 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.71528571428571 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.588212290502798 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 9.936490066225165 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 10.313840206185578 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114407407407406 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.991153846153843 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.29522540983608 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.04822289156625 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.407072243345999 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87144021739131 ms/frame
12:10:34 INFO - PID 16857 |
12:10:34 INFO - PID 16857 | Cycle 1(2): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:11:20 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810840336134454 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670249999999998 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.750618436406068 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7309472318339103 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9799204244031836 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.009158316633266 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.5764719904648365 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.44854310344828 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.67258979206049 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.465204918032785 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.170220338983052 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 9.806740196078435 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.64093971631204 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.472748691099506 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.247265306122456 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.63002906976743 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.32288679245284 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.754489247311824 ms/frame
12:11:20 INFO - PID 16857 |
12:11:20 INFO - PID 16857 | Cycle 1(3): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:12:06 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800643896976486 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.665757701915071 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7518739054290717 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7362934027777779 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0711465400271365 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1595010395010386 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.440579128440364 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3399499165275475 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.536522140221406 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587695530726259 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.312920962199305 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 10.049773869346746 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.833068592057769 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.41682291666666 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.448734439834041 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.980538922155688 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.365075757575775 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.114027777777785 ms/frame
12:12:06 INFO - PID 16857 |
12:12:06 INFO - PID 16857 | Cycle 1(4): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:12:52 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6830510375771173 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.665890924229809 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7504842473745623 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7039437819420793 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.168166666666666 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.09133946830266 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.445493685419056 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.397181663837016 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6302345215759795 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.541759002770085 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 9.934768211920527 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 9.90185643564356 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.236966292134833 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.638723404255328 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.396673553719019 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.630000000000003 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.538865384615368 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87290760869568 ms/frame
12:12:52 INFO - PID 16857 |
12:12:52 INFO - PID 16857 | Cycle 1(5): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:13:38 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6790095131505316 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6726170568561876 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7345809248554918 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7354813529921944 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.110205479452054 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.07349287169043 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.4411410550458728 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.367424242424242 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640902255639103 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572061281337045 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.067885906040267 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758609756097558 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.070295202952027 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.58235449735451 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.451141078838168 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.198536585365884 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.197873134328354 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.697112299465244 ms/frame
12:13:38 INFO - PID 16857 |
12:13:38 INFO - PID 16857 | Cycle 1(6): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:14:02 INFO - PID 16857 |
12:14:02 INFO - PID 16857 | (/builds/slave/test/build/application/firefox/firefox:16990): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:14:02 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6797704367301232 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672749999999996 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7272049510650545 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7027999999999999 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.098948087431695 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.117026748971194 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.4968764568764588 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4791304347826086 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.588556797020485 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.237028795811523 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 9.807205882352944 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 10.311649484536078 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.15258364312267 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.367590673575165 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660548523206733 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.76664705882354 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.367140151515162 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050994475138099 ms/frame
12:14:25 INFO - PID 16857 |
12:14:25 INFO - PID 16857 | Cycle 1(7): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:15:11 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838524130190797 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668511259382819 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7580785002929116 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7068088737201366 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.076698369565219 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.921754901960783 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.4252111872146127 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.351742043551085 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.738126195028682 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.420962059620594 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.383442906574402 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 9.903415841584149 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.072324723247227 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.812729729729748 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503291666666671 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977275449101807 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.365606060606076 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.755994623655903 ms/frame
12:15:11 INFO - PID 16857 |
12:15:11 INFO - PID 16857 | Cycle 1(8): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:15:57 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6887310073157007 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673333333333336 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.769404481132076 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7506999125109364 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116748971193417 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.969156746031746 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.6106498194945855 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.528544973544976 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619709737827721 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.649816384180791 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.566654929577465 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 10.205000000000002 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.11448148148148 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.47476439790576 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.298176229508186 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.839319526627225 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.320999999999982 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.931693989071038 ms/frame
12:15:57 INFO - PID 16857 |
12:15:57 INFO - PID 16857 | Cycle 1(9): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:16:43 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6839927048260384 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674291666666667 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.761876100998238 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7456413612565447 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.190621508379887 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158523908523908 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.50181446907818 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4022534013605465 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.725935114503822 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.636802816901405 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.309415807560141 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 10.103030303030293 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.153977695167294 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.583412698412696 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.245040816326515 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.697222222222214 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.587046332046326 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.051464088397802 ms/frame
12:16:43 INFO - PID 16857 |
12:16:43 INFO - PID 16857 | Cycle 1(10): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:17:29 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.689822635135135 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.672408026755853 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.739208695652174 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7427395470383282 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116145404663923 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.034122983870966 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.4098920454545434 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3510469011725306 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.505596330275234 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.511804407713498 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.171796610169492 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 9.951069651741298 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114555555555556 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.474921465968581 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399814049586766 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.699532163742706 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.24250936329588 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.815270270270293 ms/frame
12:17:29 INFO - PID 16857 |
12:17:29 INFO - PID 16857 | Cycle 1(11): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:18:15 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_startup = 1.691220405862458 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698664440734559 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7145942857142857 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.733496533795494 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_startup = 4.121524725274725 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.922784313725492 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_startup = 3.492601862630969 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1_inclip = 3.413310580204773 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.639633458646617 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510950413223137 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_startup = 10.104158249158253 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.480p.60fps.webm_scale_2_inclip = 10.258846153846147 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.990109890109887 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.475968586387415 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659493670886057 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.43434285714287 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452595419847308 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991181318681342 ms/frame
12:18:15 INFO - PID 16857 |
12:18:15 INFO - PID 16857 | Cycle 1(12): loaded http://localhost:56431/tests/video/video_playback.html (next: http://localhost:56431/tests/video/video_playback.html)
12:18:16 INFO - PID 16857 | __start_tp_report
12:18:16 INFO - PID 16857 | _x_x_mozilla_page_load
12:18:16 INFO - PID 16857 | _x_x_mozilla_page_load_details
12:18:16 INFO - PID 16857 | |i|pagename|runs|
12:18:16 INFO - PID 16857 | |0;240p.120fps.mp4_scale_1_startup;1.6798460246360585;1.6800419932810753;1.6810840336134454;1.6800643896976486;1.6830510375771173;1.6790095131505316;1.6797704367301232;1.6838524130190797;1.6887310073157007;1.6839927048260384;1.689822635135135;1.691220405862458
12:18:16 INFO - PID 16857 | |1;240p.120fps.mp4_scale_1_inclip;1.6667333333333332;1.6683277731442867;1.6670249999999998;1.665757701915071;1.665890924229809;1.6726170568561876;1.6672749999999996;1.668511259382819;1.6673333333333336;1.6674291666666667;1.672408026755853;1.6698664440734559
12:18:16 INFO - PID 16857 | |2;240p.120fps.mp4_scale_1.1_startup;1.6982031692133557;1.7637595532039976;1.750618436406068;1.7518739054290717;1.7504842473745623;1.7345809248554918;1.7272049510650545;1.7580785002929116;1.769404481132076;1.761876100998238;1.739208695652174;1.7145942857142857
12:18:16 INFO - PID 16857 | |3;240p.120fps.mp4_scale_1.1_inclip;1.6952627118644075;1.7038245315161846;1.7309472318339103;1.7362934027777779;1.7039437819420793;1.7354813529921944;1.7027999999999999;1.7068088737201366;1.7506999125109364;1.7456413612565447;1.7427395470383282;1.733496533795494
12:18:16 INFO - PID 16857 | |4;240p.120fps.mp4_scale_2_startup;4.016405622489959;3.7086711990111234;3.9799204244031836;4.0711465400271365;4.168166666666666;4.110205479452054;4.098948087431695;4.076698369565219;4.116748971193417;4.190621508379887;4.116145404663923;4.121524725274725
12:18:16 INFO - PID 16857 | |5;240p.120fps.mp4_scale_2_inclip;4.16846875;3.9615148514851497;4.009158316633266;4.1595010395010386;4.09133946830266;4.07349287169043;4.117026748971194;3.921754901960783;3.969156746031746;4.158523908523908;4.034122983870966;3.922784313725492
12:18:16 INFO - PID 16857 | |6;480p.60fps.webm_scale_1_startup;3.476732329084591;3.5380483490566053;3.5764719904648365;3.440579128440364;3.445493685419056;3.4411410550458728;3.4968764568764588;3.4252111872146127;3.6106498194945855;3.50181446907818;3.4098920454545434;3.492601862630969
12:18:16 INFO - PID 16857 | |7;480p.60fps.webm_scale_1_inclip;3.414795221843003;3.5043520140105024;3.44854310344828;3.3399499165275475;3.397181663837016;3.367424242424242;3.4791304347826086;3.351742043551085;3.528544973544976;3.4022534013605465;3.3510469011725306;3.413310580204773
12:18:16 INFO - PID 16857 | |8;480p.60fps.webm_scale_1.1_startup;5.527090239410686;5.71528571428571;5.67258979206049;5.536522140221406;5.6302345215759795;5.640902255639103;5.588556797020485;5.738126195028682;5.619709737827721;5.725935114503822;5.505596330275234;5.639633458646617
12:18:16 INFO - PID 16857 | |9;480p.60fps.webm_scale_1.1_inclip;5.589427374301675;5.588212290502798;5.465204918032785;5.587695530726259;5.541759002770085;5.572061281337045;5.237028795811523;5.420962059620594;5.649816384180791;5.636802816901405;5.511804407713498;5.510950413223137
12:18:16 INFO - PID 16857 | |10;480p.60fps.webm_scale_2_startup;9.870016447368423;9.936490066225165;10.170220338983052;10.312920962199305;9.934768211920527;10.067885906040267;9.807205882352944;10.383442906574402;10.566654929577465;10.309415807560141;10.171796610169492;10.104158249158253
12:18:16 INFO - PID 16857 | |11;480p.60fps.webm_scale_2_inclip;10.204413265306115;10.313840206185578;9.806740196078435;10.049773869346746;9.90185643564356;9.758609756097558;10.311649484536078;9.903415841584149;10.205000000000002;10.103030303030293;9.951069651741298;10.258846153846147
12:18:16 INFO - PID 16857 | |12;1080p.60fps.mp4_scale_1_startup;11.197388059701497;11.114407407407406;10.64093971631204;10.833068592057769;11.236966292134833;11.070295202952027;11.15258364312267;11.072324723247227;11.11448148148148;11.153977695167294;11.114555555555556;10.990109890109887
12:18:16 INFO - PID 16857 | |13;1080p.60fps.mp4_scale_1_inclip;10.70048128342245;10.991153846153843;10.472748691099506;10.41682291666666;10.638723404255328;10.58235449735451;10.367590673575165;10.812729729729748;10.47476439790576;10.583412698412696;10.474921465968581;10.475968586387415
12:18:16 INFO - PID 16857 | |14;1080p.60fps.mp4_scale_1.1_startup;12.500104166666672;12.29522540983608;12.247265306122456;12.448734439834041;12.396673553719019;12.451141078838168;12.660548523206733;12.503291666666671;12.298176229508186;12.245040816326515;12.399814049586766;12.659493670886057
12:18:16 INFO - PID 16857 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.977275449101807;12.04822289156625;11.63002906976743;11.980538922155688;11.630000000000003;12.198536585365884;11.76664705882354;11.977275449101807;11.839319526627225;11.697222222222214;11.699532163742706;11.43434285714287
12:18:16 INFO - PID 16857 | |16;1080p.60fps.mp4_scale_2_startup;11.407585551330797;11.407072243345999;11.32288679245284;11.365075757575775;11.538865384615368;11.197873134328354;11.367140151515162;11.365606060606076;11.320999999999982;11.587046332046326;11.24250936329588;11.452595419847308
12:18:16 INFO - PID 16857 | |17;1080p.60fps.mp4_scale_2_inclip;10.812918918918916;10.87144021739131;10.754489247311824;11.114027777777785;10.87290760869568;10.697112299465244;11.050994475138099;10.755994623655903;10.931693989071038;11.051464088397802;10.815270270270293;10.991181318681342
12:18:16 INFO - PID 16857 | __end_tp_report
12:18:16 INFO - PID 16857 | __start_cc_report
12:18:16 INFO - PID 16857 | _x_x_mozilla_cycle_collect,573
12:18:16 INFO - PID 16857 | __end_cc_report
12:18:16 INFO - PID 16857 | __startTimestamp1509823096041__endTimestamp
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | ------- Summary: start -------
12:18:16 INFO - PID 16857 | Number of tests: 18
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#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
12:18:16 INFO - PID 16857 | 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
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#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
12:18:16 INFO - PID 16857 | 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
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.75 stddev:0.02 (1.2%) stddev-sans-first:0.02
12:18:16 INFO - PID 16857 | Values: 1.7 1.8 1.8 1.8 1.8 1.7 1.7 1.8 1.8 1.8 1.7 1.7
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.72 Median:1.73 stddev:0.02 (1.1%) stddev-sans-first:0.02
12:18:16 INFO - PID 16857 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.8 1.7 1.7 1.7
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.06 Median:4.11 stddev:0.13 (3.1%) stddev-sans-first:0.13
12:18:16 INFO - PID 16857 | Values: 4.0 3.7 4.0 4.1 4.2 4.1 4.1 4.1 4.1 4.2 4.1 4.1
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.05 Median:4.08 stddev:0.09 (2.3%) stddev-sans-first:0.09
12:18:16 INFO - PID 16857 | Values: 4.2 4.0 4.0 4.2 4.1 4.1 4.1 3.9 4.0 4.2 4.0 3.9
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.49 Median:3.49 stddev:0.06 (1.8%) stddev-sans-first:0.06
12:18:16 INFO - PID 16857 | Values: 3.5 3.5 3.6 3.4 3.4 3.4 3.5 3.4 3.6 3.5 3.4 3.5
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.41 stddev:0.06 (1.8%) stddev-sans-first:0.07
12:18:16 INFO - PID 16857 | Values: 3.4 3.5 3.4 3.3 3.4 3.4 3.5 3.4 3.5 3.4 3.4 3.4
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.63 Median:5.64 stddev:0.08 (1.4%) stddev-sans-first:0.07
12:18:16 INFO - PID 16857 | Values: 5.5 5.7 5.7 5.5 5.6 5.6 5.6 5.7 5.6 5.7 5.5 5.6
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.53 Median:5.58 stddev:0.11 (2.0%) stddev-sans-first:0.12
12:18:16 INFO - PID 16857 | Values: 5.6 5.6 5.5 5.6 5.5 5.6 5.2 5.4 5.6 5.6 5.5 5.5
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.14 Median:10.48 stddev:0.23 (2.2%) stddev-sans-first:0.22
12:18:16 INFO - PID 16857 | Values: 9.9 9.9 10.2 10.3 9.9 10.1 9.8 10.4 10.6 10.3 10.2 10.1
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.06 Median:10.04 stddev:0.20 (2.0%) stddev-sans-first:0.20
12:18:16 INFO - PID 16857 | Values: 10.2 10.3 9.8 10.0 9.9 9.8 10.3 9.9 10.2 10.1 10.0 10.3
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.06 Median:11.11 stddev:0.17 (1.5%) stddev-sans-first:0.17
12:18:16 INFO - PID 16857 | Values: 11.2 11.1 10.6 10.8 11.2 11.1 11.2 11.1 11.1 11.2 11.1 11.0
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.58 Median:10.58 stddev:0.18 (1.7%) stddev-sans-first:0.18
12:18:16 INFO - PID 16857 | Values: 10.7 11.0 10.5 10.4 10.6 10.6 10.4 10.8 10.5 10.6 10.5 10.5
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.43 Median:12.45 stddev:0.14 (1.1%) stddev-sans-first:0.15
12:18:16 INFO - PID 16857 | Values: 12.5 12.3 12.2 12.4 12.4 12.5 12.7 12.5 12.3 12.2 12.4 12.7
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.82 Median:11.91 stddev:0.22 (1.8%) stddev-sans-first:0.22
12:18:16 INFO - PID 16857 | Values: 12.0 12.0 11.6 12.0 11.6 12.2 11.8 12.0 11.8 11.7 11.7 11.4
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.38 Median:11.39 stddev:0.11 (1.0%) stddev-sans-first:0.12
12:18:16 INFO - PID 16857 | Values: 11.4 11.4 11.3 11.4 11.5 11.2 11.4 11.4 11.3 11.6 11.2 11.5
12:18:16 INFO - PID 16857 |
12:18:16 INFO - PID 16857 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.89 Median:10.90 stddev:0.13 (1.2%) stddev-sans-first:0.14
12:18:16 INFO - PID 16857 | Values: 10.8 10.9 10.8 11.1 10.9 10.7 11.1 10.8 10.9 11.1 10.8 11.0
12:18:16 INFO - PID 16857 | -------- Summary: end --------
12:18:16 INFO - PID 16857 |
12:18:16 INFO - TEST-INFO | 16857: exit 0
12:18:16 INFO - TEST-OK | basic_compositor_video | took 560715ms
12:18:16 INFO - TEST-START | glvideo
12:18:16 INFO - Initialising browser for glvideo test...
12:18:16 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56431/getInfo.html -profile /tmp/tmpUzTzlD/profile
12:18:16 INFO - TEST-INFO | started process 18146 (/builds/slave/test/build/application/firefox/firefox http://localhost:56431/getInfo.html)
12:18:21 INFO - TEST-INFO | 18146: exit 0
12:18:21 INFO - Browser initialized.
12:18:21 INFO - Running cycle 1/1 for glvideo test...
12:18:21 INFO - Using env: {'DISPLAY': ':0',
12:18:21 INFO - 'HOME': '/home/cltbld',
12:18:21 INFO - 'JSGC_DISABLE_POISONING': '1',
12:18:21 INFO - 'LANG': 'en_US.UTF-8',
12:18:21 INFO - 'LANGUAGE': 'en_US:en',
12:18:21 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:18:21 INFO - 'LOGNAME': 'cltbld',
12:18:21 INFO - 'MAIL': '/var/mail/cltbld',
12:18:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:18:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:18:21 INFO - 'MOZ_CRASHREPORTER': '1',
12:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:18:21 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:18:21 INFO - 'MOZ_NO_REMOTE': '1',
12:18:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:18:21 INFO - 'NO_EM_RESTART': '1',
12:18:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:18:21 INFO - 'PWD': '/builds/slave/test',
12:18:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:18:21 INFO - 'RUST_BACKTRACE': 'full',
12:18:21 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:18:21 INFO - 'SHELL': '/bin/bash',
12:18:21 INFO - 'SHLVL': '1',
12:18:21 INFO - 'TERM': 'linux',
12:18:21 INFO - 'TMOUT': '86400',
12:18:21 INFO - 'USER': 'cltbld',
12:18:21 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:18:21 INFO - '_': '/tools/buildbot/bin/python'}
12:18:21 INFO - TEST-INFO | started process 18420 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpUzTzlD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:18:22 INFO - PID 18420 |
12:18:22 INFO - PID 18420 | (/builds/slave/test/build/application/firefox/firefox:18471): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:18:22 INFO - PID 18420 |
12:18:23 INFO - PID 18420 | [talos glvideo result] Mean tick time across 100 ticks: 6.92485 ms
12:18:23 INFO - PID 18420 | Cycle 1(1): loaded http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html)
12:18:25 INFO - PID 18420 | [talos glvideo result] Mean tick time across 100 ticks: 6.5279 ms
12:18:25 INFO - PID 18420 | Cycle 1(2): loaded http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html)
12:18:26 INFO - PID 18420 | [talos glvideo result] Mean tick time across 100 ticks: 6.628550000000001 ms
12:18:26 INFO - PID 18420 | Cycle 1(3): loaded http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html)
12:18:27 INFO - PID 18420 | [talos glvideo result] Mean tick time across 100 ticks: 6.6296 ms
12:18:27 INFO - PID 18420 | Cycle 1(4): loaded http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html)
12:18:29 INFO - PID 18420 | [talos glvideo result] Mean tick time across 100 ticks: 6.62745 ms
12:18:29 INFO - PID 18420 | Cycle 1(5): loaded http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:56431/tests/webgl/benchmarks/video/video_upload.html)
12:18:29 INFO - PID 18420 | __start_tp_report
12:18:29 INFO - PID 18420 | _x_x_mozilla_page_load
12:18:29 INFO - PID 18420 | _x_x_mozilla_page_load_details
12:18:29 INFO - PID 18420 | |i|pagename|runs|
12:18:29 INFO - PID 18420 | |0;Mean tick time across 100 ticks: ;6.92485;6.5279;6.628550000000001;6.6296;6.62745
12:18:29 INFO - PID 18420 | __end_tp_report
12:18:29 INFO - PID 18420 | __start_cc_report
12:18:29 INFO - PID 18420 | _x_x_mozilla_cycle_collect,260
12:18:29 INFO - PID 18420 | __end_cc_report
12:18:29 INFO - PID 18420 | __startTimestamp1509823109516__endTimestamp
12:18:29 INFO - PID 18420 |
12:18:29 INFO - PID 18420 | ------- Summary: start -------
12:18:29 INFO - PID 18420 | Number of tests: 1
12:18:29 INFO - PID 18420 |
12:18:29 INFO - PID 18420 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.67 Median:6.63 stddev:0.15 (2.3%) stddev-sans-first:0.05
12:18:29 INFO - PID 18420 | Values: 6.9 6.5 6.6 6.6 6.6
12:18:29 INFO - PID 18420 | -------- Summary: end --------
12:18:29 INFO - PID 18420 |
12:18:29 INFO - PID 18420 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:18:30 INFO - TEST-INFO | 18420: exit 0
12:18:30 INFO - TEST-OK | glvideo | took 13534ms
12:18:30 INFO - TEST-START | displaylist_mutate
12:18:30 INFO - Initialising browser for displaylist_mutate test...
12:18:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:56431/getInfo.html -profile /tmp/tmpf0VKKG/profile
12:18:30 INFO - TEST-INFO | started process 18646 (/builds/slave/test/build/application/firefox/firefox http://localhost:56431/getInfo.html)
12:18:34 INFO - TEST-INFO | 18646: exit 0
12:18:34 INFO - Browser initialized.
12:18:34 INFO - Running cycle 1/1 for displaylist_mutate test...
12:18:34 INFO - Using env: {'DISPLAY': ':0',
12:18:34 INFO - 'HOME': '/home/cltbld',
12:18:34 INFO - 'JSGC_DISABLE_POISONING': '1',
12:18:34 INFO - 'LANG': 'en_US.UTF-8',
12:18:34 INFO - 'LANGUAGE': 'en_US:en',
12:18:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:18:34 INFO - 'LOGNAME': 'cltbld',
12:18:34 INFO - 'MAIL': '/var/mail/cltbld',
12:18:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:18:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:18:34 INFO - 'MOZ_CRASHREPORTER': '1',
12:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:18:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:18:34 INFO - 'MOZ_NO_REMOTE': '1',
12:18:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:18:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:18:34 INFO - 'NO_EM_RESTART': '1',
12:18:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:18:34 INFO - 'PWD': '/builds/slave/test',
12:18:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:18:34 INFO - 'RUST_BACKTRACE': 'full',
12:18:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:18:34 INFO - 'SHELL': '/bin/bash',
12:18:34 INFO - 'SHLVL': '1',
12:18:34 INFO - 'TERM': 'linux',
12:18:34 INFO - 'TMOUT': '86400',
12:18:34 INFO - 'USER': 'cltbld',
12:18:34 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914',
12:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:18:34 INFO - '_': '/tools/buildbot/bin/python'}
12:18:34 INFO - TEST-INFO | started process 18915 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf0VKKG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:18:35 INFO - PID 18915 |
12:18:35 INFO - PID 18915 | (/builds/slave/test/build/application/firefox/firefox:18964): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:18:35 INFO - PID 18915 |
12:18:44 INFO - PID 18915 | Cycle 1(1): loaded http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html)
12:18:52 INFO - PID 18915 | Cycle 1(2): loaded http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html)
12:19:01 INFO - PID 18915 | Cycle 1(3): loaded http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html)
12:19:10 INFO - PID 18915 | Cycle 1(4): loaded http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html)
12:19:19 INFO - PID 18915 | Cycle 1(5): loaded http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:56431/tests/layout/benchmarks/displaylist_mutate.html)
12:19:19 INFO - PID 18915 | __start_tp_report
12:19:19 INFO - PID 18915 | _x_x_mozilla_page_load
12:19:19 INFO - PID 18915 | _x_x_mozilla_page_load_details
12:19:19 INFO - PID 18915 | |i|pagename|runs|
12:19:19 INFO - PID 18915 | |0;/displaylist_mutate.html;7948.035;8136.285;8023.895;7998.675;7998.830000000001
12:19:19 INFO - PID 18915 | __end_tp_report
12:19:19 INFO - PID 18915 | __start_cc_report
12:19:19 INFO - PID 18915 | _x_x_mozilla_cycle_collect,203
12:19:19 INFO - PID 18915 | __end_cc_report
12:19:19 INFO - PID 18915 | __startTimestamp1509823159354__endTimestamp
12:19:19 INFO - PID 18915 |
12:19:19 INFO - PID 18915 | ------- Summary: start -------
12:19:19 INFO - PID 18915 | Number of tests: 1
12:19:19 INFO - PID 18915 |
12:19:19 INFO - PID 18915 | [#0] /displaylist_mutate.html Cycles:5 Average:8021.14 Median:7998.83 stddev:70.02 (0.9%) stddev-sans-first:65.65
12:19:19 INFO - PID 18915 | Values: 7948.0 8136.3 8023.9 7998.7 7998.8
12:19:19 INFO - PID 18915 | -------- Summary: end --------
12:19:19 INFO - PID 18915 |
12:19:19 INFO - PID 18915 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:19:20 INFO - TEST-INFO | 18915: exit 0
12:19:20 INFO - TEST-OK | displaylist_mutate | took 49882ms
12:19:20 INFO - SUITE-END | took 624s
12:19:20 INFO - Completed test suite (00:10:24)
12:19:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.5645583908968295, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6798460246360585, 1.6800419932810753, 1.6810840336134454, 1.6800643896976486, 1.6830510375771173, 1.6790095131505316, 1.6797704367301232, 1.6838524130190797, 1.6887310073157007, 1.6839927048260384, 1.689822635135135, 1.691220405862458], "value": 1.6830510375771173, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6667333333333332, 1.6683277731442867, 1.6670249999999998, 1.665757701915071, 1.665890924229809, 1.6726170568561876, 1.6672749999999996, 1.668511259382819, 1.6673333333333336, 1.6674291666666667, 1.672408026755853, 1.6698664440734559], "value": 1.6674291666666667, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6982031692133557, 1.7637595532039976, 1.750618436406068, 1.7518739054290717, 1.7504842473745623, 1.7345809248554918, 1.7272049510650545, 1.7580785002929116, 1.769404481132076, 1.761876100998238, 1.739208695652174, 1.7145942857142857], "value": 1.750618436406068, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6952627118644075, 1.7038245315161846, 1.7309472318339103, 1.7362934027777779, 1.7039437819420793, 1.7354813529921944, 1.7027999999999999, 1.7068088737201366, 1.7506999125109364, 1.7456413612565447, 1.7427395470383282, 1.733496533795494], "value": 1.733496533795494, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.016405622489959, 3.7086711990111234, 3.9799204244031836, 4.0711465400271365, 4.168166666666666, 4.110205479452054, 4.098948087431695, 4.076698369565219, 4.116748971193417, 4.190621508379887, 4.116145404663923, 4.121524725274725], "value": 4.110205479452054, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.16846875, 3.9615148514851497, 4.009158316633266, 4.1595010395010386, 4.09133946830266, 4.07349287169043, 4.117026748971194, 3.921754901960783, 3.969156746031746, 4.158523908523908, 4.034122983870966, 3.922784313725492], "value": 4.034122983870966, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.476732329084591, 3.5380483490566053, 3.5764719904648365, 3.440579128440364, 3.445493685419056, 3.4411410550458728, 3.4968764568764588, 3.4252111872146127, 3.6106498194945855, 3.50181446907818, 3.4098920454545434, 3.492601862630969], "value": 3.492601862630969, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.414795221843003, 3.5043520140105024, 3.44854310344828, 3.3399499165275475, 3.397181663837016, 3.367424242424242, 3.4791304347826086, 3.351742043551085, 3.528544973544976, 3.4022534013605465, 3.3510469011725306, 3.413310580204773], "value": 3.4022534013605465, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.527090239410686, 5.71528571428571, 5.67258979206049, 5.536522140221406, 5.6302345215759795, 5.640902255639103, 5.588556797020485, 5.738126195028682, 5.619709737827721, 5.725935114503822, 5.505596330275234, 5.639633458646617], "value": 5.639633458646617, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.589427374301675, 5.588212290502798, 5.465204918032785, 5.587695530726259, 5.541759002770085, 5.572061281337045, 5.237028795811523, 5.420962059620594, 5.649816384180791, 5.636802816901405, 5.511804407713498, 5.510950413223137], "value": 5.541759002770085, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.870016447368423, 9.936490066225165, 10.170220338983052, 10.312920962199305, 9.934768211920527, 10.067885906040267, 9.807205882352944, 10.383442906574402, 10.566654929577465, 10.309415807560141, 10.171796610169492, 10.104158249158253], "value": 10.170220338983052, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.204413265306115, 10.313840206185578, 9.806740196078435, 10.049773869346746, 9.90185643564356, 9.758609756097558, 10.311649484536078, 9.903415841584149, 10.205000000000002, 10.103030303030293, 9.951069651741298, 10.258846153846147], "value": 10.049773869346746, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.197388059701497, 11.114407407407406, 10.64093971631204, 10.833068592057769, 11.236966292134833, 11.070295202952027, 11.15258364312267, 11.072324723247227, 11.11448148148148, 11.153977695167294, 11.114555555555556, 10.990109890109887], "value": 11.114407407407406, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.70048128342245, 10.991153846153843, 10.472748691099506, 10.41682291666666, 10.638723404255328, 10.58235449735451, 10.367590673575165, 10.812729729729748, 10.47476439790576, 10.583412698412696, 10.474921465968581, 10.475968586387415], "value": 10.475968586387415, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.500104166666672, 12.29522540983608, 12.247265306122456, 12.448734439834041, 12.396673553719019, 12.451141078838168, 12.660548523206733, 12.503291666666671, 12.298176229508186, 12.245040816326515, 12.399814049586766, 12.659493670886057], "value": 12.399814049586766, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.977275449101807, 12.04822289156625, 11.63002906976743, 11.980538922155688, 11.630000000000003, 12.198536585365884, 11.76664705882354, 11.977275449101807, 11.839319526627225, 11.697222222222214, 11.699532163742706, 11.43434285714287], "value": 11.76664705882354, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.407585551330797, 11.407072243345999, 11.32288679245284, 11.365075757575775, 11.538865384615368, 11.197873134328354, 11.367140151515162, 11.365606060606076, 11.320999999999982, 11.587046332046326, 11.24250936329588, 11.452595419847308], "value": 11.365606060606076, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.812918918918916, 10.87144021739131, 10.754489247311824, 11.114027777777785, 10.87290760869568, 10.697112299465244, 11.050994475138099, 10.755994623655903, 10.931693989071038, 11.051464088397802, 10.815270270270293, 10.991181318681342], "value": 10.87290760869568, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.92485, 6.5279, 6.628550000000001, 6.6296, 6.62745], "value": 6.628, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7948.035, 8136.285, 8023.895, 7998.675, 7998.830000000001], "value": 8011.362500000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
12:19:20 INFO - Return code: 0
12:19:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
12:19:20 INFO - # TBPL SUCCESS #
12:19:20 INFO - Running post-action listener: _package_coverage_data
12:19:20 INFO - Running post-action listener: _resource_record_post_action
12:19:20 INFO - [mozharness: 2017-11-04 19:19:20.590293Z] Finished run-tests step (success)
12:19:20 INFO - Running post-run listener: _resource_record_post_run
12:19:20 INFO - Total resource usage - Wall time: 641s; CPU: 37.0%; Read bytes: 4096; Write bytes: 298397696; Read time: 12; Write time: 109448
12:19:20 INFO - TinderboxPrint: CPU usage
36.6%
12:19:20 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12
12:19:20 INFO - TinderboxPrint: I/O write bytes / time
298,397,696 / 109,448
12:19:20 INFO - TinderboxPrint: CPU idle
3,187.5 (63.3%)
12:19:20 INFO - TinderboxPrint: CPU system
313.6 (6.2%)
12:19:20 INFO - TinderboxPrint: CPU user
1,514.5 (30.1%)
12:19:20 INFO - TinderboxPrint: Swap in / out
0 / 0
12:19:20 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 4460544; Read time: 0; Write time: 412
12:19:20 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
12:19:20 INFO - run-tests - Wall time: 625s; CPU: 37.0%; Read bytes: 4096; Write bytes: 293937152; Read time: 12; Write time: 109036
12:19:20 INFO - Running post-run listener: _upload_blobber_files
12:19:20 INFO - Blob upload gear active.
12:19:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
12:19:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
12:19:20 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
12:19:20 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
12:19:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:21 INFO - SNIMissingWarning
12:19:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:21 INFO - InsecurePlatformWarning
12:19:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:19:21 INFO - SubjectAltNameWarning
12:19:21 INFO - (blobuploader) - INFO - Open directory for files ...
12:19:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
12:19:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:19:21 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:19:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:21 INFO - InsecurePlatformWarning
12:19:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:19:21 INFO - SubjectAltNameWarning
12:19:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:21 INFO - InsecurePlatformWarning
12:19:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
12:19:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:19:22 INFO - (blobuploader) - INFO - Done attempting.
12:19:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
12:19:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:19:22 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:19:22 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:22 INFO - InsecurePlatformWarning
12:19:22 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:19:22 INFO - SubjectAltNameWarning
12:19:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:24 INFO - InsecurePlatformWarning
12:19:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:19:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:19:24 INFO - (blobuploader) - INFO - Done attempting.
12:19:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
12:19:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:19:24 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:19:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:24 INFO - InsecurePlatformWarning
12:19:25 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:19:25 INFO - SubjectAltNameWarning
12:19:25 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:25 INFO - InsecurePlatformWarning
12:19:26 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
12:19:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:19:26 INFO - (blobuploader) - INFO - Done attempting.
12:19:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
12:19:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:19:26 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:19:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:26 INFO - InsecurePlatformWarning
12:19:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:19:26 INFO - SubjectAltNameWarning
12:19:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:19:26 INFO - InsecurePlatformWarning
12:19:26 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
12:19:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:19:26 INFO - (blobuploader) - INFO - Done attempting.
12:19:26 INFO - (blobuploader) - INFO - Iteration through files over.
12:19:27 INFO - Return code: 0
12:19:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
12:19:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
12:19:27 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/278aabc96d5ec1393894b73a1805d1f71f029285c229ae5bab19451863a37135e09b86e8bf64958cc8a87acd99401ed0faab816aa6e57ad1ee83f9714feaf79f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e93d62db6ca40ee69d800cec93893e9fa688396cca62c04b047c733b4b2f2bf48d0e998951d4a75f18cd69af89484875e427f238d09b5a1bbbe18907766b8c87", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f7d82f966862d7a71bb152e448fa26de3930e477930ed53bbe289c521469167fc15c272479847d6e056a91e063304e441d36e61c62fdbe76026cd37c59727a15", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe6b3a0192c5842c14838a474dfa2968349ae967c168e7efb4b4316079b141a327bc3d16e9eabc49793302487d22371915bdfa347c16402a15fabd61c8588d77"}
12:19:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
12:19:27 INFO - Writing to file /builds/slave/test/properties/blobber_files
12:19:27 INFO - Contents:
12:19:27 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/278aabc96d5ec1393894b73a1805d1f71f029285c229ae5bab19451863a37135e09b86e8bf64958cc8a87acd99401ed0faab816aa6e57ad1ee83f9714feaf79f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e93d62db6ca40ee69d800cec93893e9fa688396cca62c04b047c733b4b2f2bf48d0e998951d4a75f18cd69af89484875e427f238d09b5a1bbbe18907766b8c87", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f7d82f966862d7a71bb152e448fa26de3930e477930ed53bbe289c521469167fc15c272479847d6e056a91e063304e441d36e61c62fdbe76026cd37c59727a15", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe6b3a0192c5842c14838a474dfa2968349ae967c168e7efb4b4316079b141a327bc3d16e9eabc49793302487d22371915bdfa347c16402a15fabd61c8588d77"}
12:19:27 INFO - Running post-run listener: copy_logs_to_upload_dir
12:19:27 INFO - Copying logs to upload dir...
12:19:27 INFO - mkdir: /builds/slave/test/build/upload/logs
12:19:27 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=703.879334
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 43 secs) (at 2017-11-04 12:19:26.720654) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-04 12:19:26.728123) =========
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=1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/278aabc96d5ec1393894b73a1805d1f71f029285c229ae5bab19451863a37135e09b86e8bf64958cc8a87acd99401ed0faab816aa6e57ad1ee83f9714feaf79f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e93d62db6ca40ee69d800cec93893e9fa688396cca62c04b047c733b4b2f2bf48d0e998951d4a75f18cd69af89484875e427f238d09b5a1bbbe18907766b8c87", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f7d82f966862d7a71bb152e448fa26de3930e477930ed53bbe289c521469167fc15c272479847d6e056a91e063304e441d36e61c62fdbe76026cd37c59727a15", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe6b3a0192c5842c14838a474dfa2968349ae967c168e7efb4b4316079b141a327bc3d16e9eabc49793302487d22371915bdfa347c16402a15fabd61c8588d77"}
build_url:https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011338
build_url: 'https://queue.taskcluster.net/v1/task/Whhu0yUYRLuESDbWfXiKXQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/278aabc96d5ec1393894b73a1805d1f71f029285c229ae5bab19451863a37135e09b86e8bf64958cc8a87acd99401ed0faab816aa6e57ad1ee83f9714feaf79f", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/e93d62db6ca40ee69d800cec93893e9fa688396cca62c04b047c733b4b2f2bf48d0e998951d4a75f18cd69af89484875e427f238d09b5a1bbbe18907766b8c87", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/f7d82f966862d7a71bb152e448fa26de3930e477930ed53bbe289c521469167fc15c272479847d6e056a91e063304e441d36e61c62fdbe76026cd37c59727a15", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/fe6b3a0192c5842c14838a474dfa2968349ae967c168e7efb4b4316079b141a327bc3d16e9eabc49793302487d22371915bdfa347c16402a15fabd61c8588d77"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-04 12:19:26.760503) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-04 12:19:26.760828) =========
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=1f5564757af27664ca02d71f000001b0-1509819131.968217-1496599914
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004525
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-04 12:19:26.843399) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-04 12:19:26.846716) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-04 12:19:26.847028) =========
========= Total master_lag: 0.26 =========