builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-044
starttime: 1513096326.72
results: success (0)
revision: 96355d26b932219a8081bfe21ee80048e12ce274
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:06.719931) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:06.720519) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:06.721027) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:06.786933) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:06.787263) =========
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=88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-12-12 08:32:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 49.5K=0.2s
2017-12-12 08:32:07 (49.5 KB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.621450
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:07.430888) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:07.431578) =========
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=88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.016613
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:07.482128) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-12 08:32:07.482530) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 96355d26b932219a8081bfe21ee80048e12ce274 --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 96355d26b932219a8081bfe21ee80048e12ce274 --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=88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-12-12 08:32:07,534 truncating revision to first 12 chars
2017-12-12 08:32:07,534 Setting DEBUG logging.
2017-12-12 08:32:07,534 attempt 1/10
2017-12-12 08:32:07,534 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/96355d26b932?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-12-12 08:32:07,904 unpacking tar archive at: mozilla-beta-96355d26b932/testing/mozharness/
program finished with exit code 0
elapsedTime=1.145222
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-12 08:32:08.645977) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:08.646326) =========
script_repo_revision: 96355d26b932219a8081bfe21ee80048e12ce274
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:08.646783) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:08.647088) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-12 08:32:08.662176) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 46 secs) (at 2017-12-12 08:32:08.662477) =========
/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=88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
08:32:08 INFO - MultiFileLogger online at 20171212 08:32:08 in /builds/slave/test
08:32:08 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
08:32:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
08:32:08 INFO - {'append_to_log': False,
08:32:08 INFO - 'base_work_dir': '/builds/slave/test',
08:32:08 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
08:32:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
08:32:08 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
08:32:08 INFO - 'buildbot_json_path': 'buildprops.json',
08:32:08 INFO - 'code_coverage': False,
08:32:08 INFO - 'config_files': ('talos/linux_config.py',),
08:32:08 INFO - 'default_actions': ('clobber',
08:32:08 INFO - 'read-buildbot-config',
08:32:08 INFO - 'download-and-extract',
08:32:08 INFO - 'populate-webroot',
08:32:08 INFO - 'create-virtualenv',
08:32:08 INFO - 'install',
08:32:08 INFO - 'setup-mitmproxy',
08:32:08 INFO - 'run-tests'),
08:32:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
08:32:08 INFO - 'disable_ccov_upload': False,
08:32:08 INFO - 'disable_stylo': False,
08:32:08 INFO - 'download_minidump_stackwalk': True,
08:32:08 INFO - 'download_symbols': 'ondemand',
08:32:08 INFO - 'e10s': False,
08:32:08 INFO - 'enable_stylo': False,
08:32:08 INFO - 'enable_webrender': False,
08:32:08 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
08:32:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
08:32:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
08:32:08 INFO - 'gecko_profile': False,
08:32:08 INFO - 'gecko_profile_interval': 0,
08:32:08 INFO - 'installer_path': 'installer.exe',
08:32:08 INFO - 'jsd_code_coverage': False,
08:32:08 INFO - 'log_level': 'info',
08:32:08 INFO - 'log_name': 'talos',
08:32:08 INFO - 'log_to_console': True,
08:32:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
08:32:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
08:32:08 INFO - 'opt_config_files': (),
08:32:08 INFO - 'pip_index': False,
08:32:08 INFO - 'suite': 'g4-e10s',
08:32:08 INFO - 'system_bits': '32',
08:32:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
08:32:08 INFO - 'title': 'talos-linux64-ix-044',
08:32:08 INFO - 'tooltool_cache': '/builds/tooltool_cache',
08:32:08 INFO - 'use_talos_json': True,
08:32:08 INFO - 'verify': 'False',
08:32:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
08:32:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
08:32:08 INFO - 'work_dir': 'build'}
08:32:08 INFO - [mozharness: 2017-12-12 16:32:08.853425Z] Running clobber step.
08:32:08 INFO - Running pre-action listener: _resource_record_pre_action
08:32:08 INFO - Running main action method: clobber
08:32:08 INFO - rmtree: /builds/slave/test/build
08:32:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
08:32:10 INFO - Running post-action listener: _resource_record_post_action
08:32:10 INFO - [mozharness: 2017-12-12 16:32:10.456628Z] Finished clobber step (success)
08:32:10 INFO - [mozharness: 2017-12-12 16:32:10.456725Z] Running read-buildbot-config step.
08:32:10 INFO - Running pre-action listener: _resource_record_pre_action
08:32:10 INFO - Running main action method: read_buildbot_config
08:32:10 INFO - Using buildbot properties:
08:32:10 INFO - {
08:32:10 INFO - "project": "",
08:32:10 INFO - "product": "firefox",
08:32:10 INFO - "who": "archaeopteryx@coole-files.de",
08:32:10 INFO - "installer_path": "public/build/target.tar.bz2",
08:32:10 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
08:32:10 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
08:32:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
08:32:10 INFO - "stage_platform": "linux64",
08:32:10 INFO - "basedir": "/builds/slave/test",
08:32:10 INFO - "buildnumber": 83,
08:32:10 INFO - "platform": "ubuntu64_hw",
08:32:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
08:32:10 INFO - "slavebuilddir": "test",
08:32:10 INFO - "taskId": "CZWRYsXuTWqSZjzP0UXFpw",
08:32:10 INFO - "branch": "mozilla-beta",
08:32:10 INFO - "script_repo_revision": "production",
08:32:10 INFO - "revision": "96355d26b932219a8081bfe21ee80048e12ce274",
08:32:10 INFO - "slavename": "talos-linux64-ix-044",
08:32:10 INFO - "repo_path": "releases/mozilla-beta"
08:32:10 INFO - }
08:32:10 INFO - Finding installer, test and symbols from parent task.
08:32:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CZWRYsXuTWqSZjzP0UXFpw'}, attempt #1
08:32:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CZWRYsXuTWqSZjzP0UXFpw'}, attempt #1
08:32:11 INFO - Task dependencies: R60fzD-9QFGh1HypQ841WQ
08:32:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ'}, attempt #1
08:32:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2
08:32:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.test_packages.json
08:32:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.crashreporter-symbols.zip
08:32:11 INFO - Running post-action listener: _resource_record_post_action
08:32:11 INFO - [mozharness: 2017-12-12 16:32:11.880829Z] Finished read-buildbot-config step (success)
08:32:11 INFO - [mozharness: 2017-12-12 16:32:11.881131Z] Running download-and-extract step.
08:32:11 INFO - Running pre-action listener: _resource_record_pre_action
08:32:11 INFO - Running main action method: download_and_extract
08:32:11 INFO - mkdir: /builds/slave/test/build/tests
08:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:12 INFO - trying https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.test_packages.json
08:32:12 INFO - Downloading https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
08:32:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
08:32:12 INFO - Downloaded 989 bytes.
08:32:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
08:32:12 INFO - Using the following test package requirements:
08:32:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
08:32:12 INFO - u'common': [u'target.common.tests.zip'],
08:32:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
08:32:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
08:32:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
08:32:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
08:32:12 INFO - u'mozbase': [u'target.common.tests.zip'],
08:32:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
08:32:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
08:32:12 INFO - u'web-platform': [u'target.common.tests.zip',
08:32:12 INFO - u'target.web-platform.tests.tar.gz'],
08:32:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
08:32:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
08:32:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.common.tests.zip
08:32:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
08:32:12 INFO - Fetch https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.common.tests.zip into memory
08:32:14 INFO - Content-Length response header: 58310828
08:32:14 INFO - Bytes received: 58310828
08:32:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.talos.tests.zip
08:32:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
08:32:25 INFO - Fetch https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.talos.tests.zip into memory
08:32:26 INFO - Content-Length response header: 18772095
08:32:26 INFO - Bytes received: 18772095
08:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:26 INFO - trying https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2
08:32:26 INFO - Downloading https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
08:32:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
08:32:27 INFO - Downloaded 54069106 bytes.
08:32:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2
08:32:27 INFO - mkdir: /builds/slave/test/properties
08:32:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
08:32:27 INFO - Writing to file /builds/slave/test/properties/build_url
08:32:27 INFO - Contents:
08:32:27 INFO - build_url:https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2
08:32:27 INFO - Running post-action listener: _resource_record_post_action
08:32:27 INFO - Running post-action listener: find_tests_for_verification
08:32:27 INFO - Running post-action listener: set_extra_try_arguments
08:32:27 INFO - [mozharness: 2017-12-12 16:32:27.886031Z] Finished download-and-extract step (success)
08:32:27 INFO - [mozharness: 2017-12-12 16:32:27.886126Z] Running populate-webroot step.
08:32:27 INFO - Running pre-action listener: _resource_record_pre_action
08:32:27 INFO - Running main action method: populate_webroot
08:32:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
08:32:27 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['tresize',
08:32:27 INFO - 'tcanvasmark']},
08:32:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
08:32:27 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['dromaeo_css',
08:32:27 INFO - 'kraken']},
08:32:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:32:27 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:32:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'tests': ['damp', 'tps']},
08:32:27 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['damp', 'tps']},
08:32:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
08:32:27 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['dromaeo_dom']},
08:32:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
08:32:27 INFO - 'glvideo',
08:32:27 INFO - 'displaylist_mutate']},
08:32:27 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['basic_compositor_video',
08:32:27 INFO - 'glvideo']},
08:32:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
08:32:27 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['ts_paint_webext',
08:32:27 INFO - 'tp5o_webext']},
08:32:27 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
08:32:27 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:27 INFO - 'talos_options': ['--mitmproxy',
08:32:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:27 INFO - '--firstNonBlankPaint'],
08:32:27 INFO - 'tests': ['tp6_google_heavy',
08:32:27 INFO - 'tp6_youtube_heavy',
08:32:27 INFO - 'tp6_amazon_heavy',
08:32:27 INFO - 'tp6_facebook_heavy']},
08:32:27 INFO - 'other-e10s': {'tests': ['a11yr',
08:32:27 INFO - 'ts_paint',
08:32:27 INFO - 'tpaint',
08:32:27 INFO - 'sessionrestore',
08:32:27 INFO - 'sessionrestore_many_windows',
08:32:27 INFO - 'sessionrestore_no_auto_restore',
08:32:27 INFO - 'tabpaint',
08:32:27 INFO - 'cpstartup']},
08:32:27 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['a11yr',
08:32:27 INFO - 'ts_paint',
08:32:27 INFO - 'tpaint',
08:32:27 INFO - 'sessionrestore',
08:32:27 INFO - 'sessionrestore_many_windows',
08:32:27 INFO - 'sessionrestore_no_auto_restore',
08:32:27 INFO - 'tabpaint',
08:32:27 INFO - 'cpstartup']},
08:32:27 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
08:32:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
08:32:27 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['perf_reftest_singletons']},
08:32:27 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['perf_reftest']},
08:32:27 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
08:32:27 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['speedometer']},
08:32:27 INFO - 'svgr-e10s': {'tests': ['tsvgx',
08:32:27 INFO - 'tsvgr_opacity',
08:32:27 INFO - 'tart',
08:32:27 INFO - 'tscrollx',
08:32:27 INFO - 'tsvg_static']},
08:32:27 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['tsvgx',
08:32:27 INFO - 'tsvgr_opacity',
08:32:27 INFO - 'tart',
08:32:27 INFO - 'tscrollx',
08:32:27 INFO - 'tsvg_static']},
08:32:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
08:32:27 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'talos_options': ['--disable-stylo'],
08:32:27 INFO - 'tests': ['tp5o']},
08:32:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:27 INFO - 'talos_options': ['--mitmproxy',
08:32:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:27 INFO - '--firstNonBlankPaint'],
08:32:27 INFO - 'tests': ['tp6_google',
08:32:27 INFO - 'tp6_youtube',
08:32:27 INFO - 'tp6_amazon',
08:32:27 INFO - 'tp6_facebook']},
08:32:27 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:27 INFO - 'talos_options': ['--disable-stylo',
08:32:27 INFO - '--mitmproxy',
08:32:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:27 INFO - '--firstNonBlankPaint'],
08:32:27 INFO - 'tests': ['tp6_google',
08:32:27 INFO - 'tp6_youtube',
08:32:27 INFO - 'tp6_amazon',
08:32:27 INFO - 'tp6_facebook']},
08:32:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:27 INFO - 'talos_options': ['--stylo-threads=1',
08:32:27 INFO - '--mitmproxy',
08:32:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:27 INFO - '--firstNonBlankPaint'],
08:32:27 INFO - 'tests': ['tp6_google',
08:32:27 INFO - 'tp6_youtube',
08:32:27 INFO - 'tp6_amazon',
08:32:27 INFO - 'tp6_facebook']},
08:32:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'talos_options': ['--xperf_path',
08:32:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:32:27 INFO - 'tests': ['tp5n']},
08:32:27 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:27 INFO - 'talos_options': ['--disable-stylo',
08:32:27 INFO - '--xperf_path',
08:32:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:32:27 INFO - 'tests': ['tp5n']}}}
08:32:27 INFO - Running post-action listener: _resource_record_post_action
08:32:27 INFO - [mozharness: 2017-12-12 16:32:27.904679Z] Finished populate-webroot step (success)
08:32:27 INFO - [mozharness: 2017-12-12 16:32:27.904771Z] Running create-virtualenv step.
08:32:27 INFO - Running pre-action listener: _resource_record_pre_action
08:32:27 INFO - Running main action method: create_virtualenv
08:32:27 INFO - Creating virtualenv /builds/slave/test/build/venv
08:32:27 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
08:32:27 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
08:32:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
08:32:28 INFO - Using real prefix '/usr'
08:32:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python
08:32:28 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
08:32:29 INFO - Installing setuptools, pip, wheel...done.
08:32:29 INFO - Return code: 0
08:32:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
08:32:29 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')]}
08:32:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:29 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')]}
08:32:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:29 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:29 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
08:32:29 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
08:32:29 INFO - Using env: {'DISPLAY': ':0',
08:32:29 INFO - 'HOME': '/home/cltbld',
08:32:29 INFO - 'LANG': 'en_US.UTF-8',
08:32:29 INFO - 'LANGUAGE': 'en_US:en',
08:32:29 INFO - 'LOGNAME': 'cltbld',
08:32:29 INFO - 'MAIL': '/var/mail/cltbld',
08:32:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:29 INFO - 'MOZ_NO_REMOTE': '1',
08:32:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:29 INFO - 'NO_EM_RESTART': '1',
08:32:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:29 INFO - 'PWD': '/builds/slave/test',
08:32:29 INFO - 'SHELL': '/bin/bash',
08:32:29 INFO - 'SHLVL': '1',
08:32:29 INFO - 'TERM': 'linux',
08:32:29 INFO - 'TMOUT': '86400',
08:32:29 INFO - 'USER': 'cltbld',
08:32:29 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:29 INFO - '_': '/tools/buildbot/bin/python'}
08:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
08:32:30 INFO - Return code: 0
08:32:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
08:32:30 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')]}
08:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:30 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')]}
08:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:30 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:30 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
08:32:30 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
08:32:30 INFO - Using env: {'DISPLAY': ':0',
08:32:30 INFO - 'HOME': '/home/cltbld',
08:32:30 INFO - 'LANG': 'en_US.UTF-8',
08:32:30 INFO - 'LANGUAGE': 'en_US:en',
08:32:30 INFO - 'LOGNAME': 'cltbld',
08:32:30 INFO - 'MAIL': '/var/mail/cltbld',
08:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:30 INFO - 'MOZ_NO_REMOTE': '1',
08:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:30 INFO - 'NO_EM_RESTART': '1',
08:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:30 INFO - 'PWD': '/builds/slave/test',
08:32:30 INFO - 'SHELL': '/bin/bash',
08:32:30 INFO - 'SHLVL': '1',
08:32:30 INFO - 'TERM': 'linux',
08:32:30 INFO - 'TMOUT': '86400',
08:32:30 INFO - 'USER': 'cltbld',
08:32:30 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:30 INFO - '_': '/tools/buildbot/bin/python'}
08:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:30 INFO - Collecting psutil>=3.1.1
08:32:31 INFO - Installing collected packages: psutil
08:32:31 INFO - Successfully installed psutil-3.1.1
08:32:31 INFO - Return code: 0
08:32:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
08:32: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')]}
08:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32: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')]}
08:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:31 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:31 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
08:32:31 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
08:32:31 INFO - Using env: {'DISPLAY': ':0',
08:32:31 INFO - 'HOME': '/home/cltbld',
08:32:31 INFO - 'LANG': 'en_US.UTF-8',
08:32:31 INFO - 'LANGUAGE': 'en_US:en',
08:32:31 INFO - 'LOGNAME': 'cltbld',
08:32:31 INFO - 'MAIL': '/var/mail/cltbld',
08:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:31 INFO - 'MOZ_NO_REMOTE': '1',
08:32:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:31 INFO - 'NO_EM_RESTART': '1',
08:32:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:31 INFO - 'PWD': '/builds/slave/test',
08:32:31 INFO - 'SHELL': '/bin/bash',
08:32:31 INFO - 'SHLVL': '1',
08:32:31 INFO - 'TERM': 'linux',
08:32:31 INFO - 'TMOUT': '86400',
08:32:31 INFO - 'USER': 'cltbld',
08:32:31 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:31 INFO - '_': '/tools/buildbot/bin/python'}
08:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:32 INFO - Collecting mozsystemmonitor==0.3
08:32:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
08:32:33 INFO - Installing collected packages: mozsystemmonitor
08:32:33 INFO - Successfully installed mozsystemmonitor-0.3
08:32:33 INFO - Return code: 0
08:32:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
08:32:33 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')]}
08:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:33 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')]}
08:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:33 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:33 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
08:32:33 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
08:32:33 INFO - Using env: {'DISPLAY': ':0',
08:32:33 INFO - 'HOME': '/home/cltbld',
08:32:33 INFO - 'LANG': 'en_US.UTF-8',
08:32:33 INFO - 'LANGUAGE': 'en_US:en',
08:32:33 INFO - 'LOGNAME': 'cltbld',
08:32:33 INFO - 'MAIL': '/var/mail/cltbld',
08:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:33 INFO - 'MOZ_NO_REMOTE': '1',
08:32:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:33 INFO - 'NO_EM_RESTART': '1',
08:32:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:33 INFO - 'PWD': '/builds/slave/test',
08:32:33 INFO - 'SHELL': '/bin/bash',
08:32:33 INFO - 'SHLVL': '1',
08:32:33 INFO - 'TERM': 'linux',
08:32:33 INFO - 'TMOUT': '86400',
08:32:33 INFO - 'USER': 'cltbld',
08:32:33 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:33 INFO - '_': '/tools/buildbot/bin/python'}
08:32:33 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:33 INFO - Collecting jsonschema==2.5.1
08:32:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
08:32:34 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
08:32:35 INFO - Installing collected packages: functools32, jsonschema
08:32:36 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
08:32:36 INFO - Return code: 0
08:32:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
08:32:36 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')]}
08:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:36 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')]}
08:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:36 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:36 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
08:32:36 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
08:32:36 INFO - Using env: {'DISPLAY': ':0',
08:32:36 INFO - 'HOME': '/home/cltbld',
08:32:36 INFO - 'LANG': 'en_US.UTF-8',
08:32:36 INFO - 'LANGUAGE': 'en_US:en',
08:32:36 INFO - 'LOGNAME': 'cltbld',
08:32:36 INFO - 'MAIL': '/var/mail/cltbld',
08:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:36 INFO - 'MOZ_NO_REMOTE': '1',
08:32:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:36 INFO - 'NO_EM_RESTART': '1',
08:32:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:36 INFO - 'PWD': '/builds/slave/test',
08:32:36 INFO - 'SHELL': '/bin/bash',
08:32:36 INFO - 'SHLVL': '1',
08:32:36 INFO - 'TERM': 'linux',
08:32:36 INFO - 'TMOUT': '86400',
08:32:36 INFO - 'USER': 'cltbld',
08:32:36 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:36 INFO - '_': '/tools/buildbot/bin/python'}
08:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
08:32:36 INFO - Return code: 0
08:32:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
08:32:36 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')]}
08:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:36 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')]}
08:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:36 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:36 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
08:32:36 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
08:32:36 INFO - Using env: {'DISPLAY': ':0',
08:32:36 INFO - 'HOME': '/home/cltbld',
08:32:36 INFO - 'LANG': 'en_US.UTF-8',
08:32:36 INFO - 'LANGUAGE': 'en_US:en',
08:32:36 INFO - 'LOGNAME': 'cltbld',
08:32:36 INFO - 'MAIL': '/var/mail/cltbld',
08:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:36 INFO - 'MOZ_NO_REMOTE': '1',
08:32:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:36 INFO - 'NO_EM_RESTART': '1',
08:32:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:36 INFO - 'PWD': '/builds/slave/test',
08:32:36 INFO - 'SHELL': '/bin/bash',
08:32:36 INFO - 'SHLVL': '1',
08:32:36 INFO - 'TERM': 'linux',
08:32:36 INFO - 'TMOUT': '86400',
08:32:36 INFO - 'USER': 'cltbld',
08:32:36 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:36 INFO - '_': '/tools/buildbot/bin/python'}
08:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:37 INFO - Collecting blobuploader==1.2.4
08:32:38 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
08:32:39 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
08:32:40 INFO - Installing collected packages: requests, docopt, blobuploader
08:32:40 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
08:32:40 INFO - Return code: 0
08:32:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:32:40 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')]}
08:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:40 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')]}
08:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:40 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:40 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
08:32:40 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
08:32:40 INFO - Using env: {'DISPLAY': ':0',
08:32:40 INFO - 'HOME': '/home/cltbld',
08:32:40 INFO - 'LANG': 'en_US.UTF-8',
08:32:40 INFO - 'LANGUAGE': 'en_US:en',
08:32:40 INFO - 'LOGNAME': 'cltbld',
08:32:40 INFO - 'MAIL': '/var/mail/cltbld',
08:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:40 INFO - 'MOZ_NO_REMOTE': '1',
08:32:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:40 INFO - 'NO_EM_RESTART': '1',
08:32:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:40 INFO - 'PWD': '/builds/slave/test',
08:32:40 INFO - 'SHELL': '/bin/bash',
08:32:40 INFO - 'SHLVL': '1',
08:32:40 INFO - 'TERM': 'linux',
08:32:40 INFO - 'TMOUT': '86400',
08:32:40 INFO - 'USER': 'cltbld',
08:32:40 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:40 INFO - '_': '/tools/buildbot/bin/python'}
08:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:41 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:32:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:32:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:32:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:32:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:32:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:32:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:32:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:32:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:32:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:32:43 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:32:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:32:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:32:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:32:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:32:43 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:32:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
08:32:44 INFO - Running setup.py install for manifestparser: started
08:32:44 INFO - Running setup.py install for manifestparser: finished with status 'done'
08:32:44 INFO - Running setup.py install for mozcrash: started
08:32:44 INFO - Running setup.py install for mozcrash: finished with status 'done'
08:32:44 INFO - Running setup.py install for mozdebug: started
08:32:44 INFO - Running setup.py install for mozdebug: finished with status 'done'
08:32:44 INFO - Running setup.py install for mozdevice: started
08:32:45 INFO - Running setup.py install for mozdevice: finished with status 'done'
08:32:45 INFO - Running setup.py install for mozfile: started
08:32:45 INFO - Running setup.py install for mozfile: finished with status 'done'
08:32:45 INFO - Running setup.py install for mozhttpd: started
08:32:45 INFO - Running setup.py install for mozhttpd: finished with status 'done'
08:32:45 INFO - Running setup.py install for mozinfo: started
08:32:45 INFO - Running setup.py install for mozinfo: finished with status 'done'
08:32:45 INFO - Running setup.py install for mozInstall: started
08:32:46 INFO - Running setup.py install for mozInstall: finished with status 'done'
08:32:46 INFO - Running setup.py install for mozleak: started
08:32:46 INFO - Running setup.py install for mozleak: finished with status 'done'
08:32:46 INFO - Running setup.py install for mozlog: started
08:32:46 INFO - Running setup.py install for mozlog: finished with status 'done'
08:32:46 INFO - Running setup.py install for moznetwork: started
08:32:46 INFO - Running setup.py install for moznetwork: finished with status 'done'
08:32:46 INFO - Running setup.py install for mozprocess: started
08:32:46 INFO - Running setup.py install for mozprocess: finished with status 'done'
08:32:46 INFO - Running setup.py install for mozprofile: started
08:32:47 INFO - Running setup.py install for mozprofile: finished with status 'done'
08:32:47 INFO - Running setup.py install for mozrunner: started
08:32:47 INFO - Running setup.py install for mozrunner: finished with status 'done'
08:32:47 INFO - Running setup.py install for mozscreenshot: started
08:32:47 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
08:32:47 INFO - Running setup.py install for moztest: started
08:32:47 INFO - Running setup.py install for moztest: finished with status 'done'
08:32:47 INFO - Running setup.py install for mozversion: started
08:32:48 INFO - Running setup.py install for mozversion: finished with status 'done'
08:32:48 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
08:32:48 INFO - Return code: 0
08:32:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:32:48 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')]}
08:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:48 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')]}
08:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:48 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:48 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
08:32:48 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
08:32:48 INFO - Using env: {'DISPLAY': ':0',
08:32:48 INFO - 'HOME': '/home/cltbld',
08:32:48 INFO - 'LANG': 'en_US.UTF-8',
08:32:48 INFO - 'LANGUAGE': 'en_US:en',
08:32:48 INFO - 'LOGNAME': 'cltbld',
08:32:48 INFO - 'MAIL': '/var/mail/cltbld',
08:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:48 INFO - 'MOZ_NO_REMOTE': '1',
08:32:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:48 INFO - 'NO_EM_RESTART': '1',
08:32:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:48 INFO - 'PWD': '/builds/slave/test',
08:32:48 INFO - 'SHELL': '/bin/bash',
08:32:48 INFO - 'SHLVL': '1',
08:32:48 INFO - 'TERM': 'linux',
08:32:48 INFO - 'TMOUT': '86400',
08:32:48 INFO - 'USER': 'cltbld',
08:32:48 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:48 INFO - '_': '/tools/buildbot/bin/python'}
08:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:48 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:32:49 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))
08:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:32:49 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))
08:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:32:49 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))
08:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:32:49 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))
08:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:32:49 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))
08:32:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:32:50 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))
08:32:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:32:50 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))
08:32:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:32:50 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))
08:32:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:32:50 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))
08:32:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:32:50 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))
08:32:50 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:32:51 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))
08:32:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:32:51 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))
08:32:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:32:51 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))
08:32:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:32:51 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))
08:32:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:32:51 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))
08:32:51 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:32:52 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))
08:32:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:32:52 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))
08:32:52 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
08:32:53 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
08:32:53 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))
08:32:53 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))
08:32:53 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))
08:32:53 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))
08:32:53 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))
08:32:53 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))
08:32:53 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
08:32:54 INFO - Installing collected packages: six, blessings
08:32:54 INFO - Successfully installed blessings-1.6 six-1.10.0
08:32:54 INFO - Return code: 0
08:32:54 INFO - Done creating virtualenv /builds/slave/test/build/venv.
08:32:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:32:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:32:55 INFO - Reading from file tmpfile_stdout
08:32:55 INFO - Reading from file tmpfile_stderr
08:32:55 INFO - Current package versions:
08:32:55 INFO - blessings == 1.6
08:32:55 INFO - blobuploader == 1.2.4
08:32:55 INFO - docopt == 0.6.1
08:32:55 INFO - functools32 == 3.2.3.post2
08:32:55 INFO - jsonschema == 2.5.1
08:32:55 INFO - manifestparser == 1.2
08:32:55 INFO - mozInstall == 1.14
08:32:55 INFO - mozcrash == 1.0
08:32:55 INFO - mozdebug == 0.1
08:32:55 INFO - mozdevice == 0.51
08:32:55 INFO - mozfile == 1.2
08:32:55 INFO - mozhttpd == 0.7
08:32:55 INFO - mozinfo == 0.10
08:32:55 INFO - mozleak == 0.1
08:32:55 INFO - mozlog == 3.5
08:32:55 INFO - moznetwork == 0.27
08:32:55 INFO - mozprocess == 0.25
08:32:55 INFO - mozprofile == 0.29
08:32:55 INFO - mozrunner == 6.13
08:32:55 INFO - mozscreenshot == 0.1
08:32:55 INFO - mozsystemmonitor == 0.3
08:32:55 INFO - moztest == 0.8
08:32:55 INFO - mozversion == 1.4
08:32:55 INFO - psutil == 3.1.1
08:32:55 INFO - requests == 1.2.3
08:32:55 INFO - six == 1.10.0
08:32:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:32: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')]}
08:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32: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')]}
08:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:55 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 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:32:55 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
08:32:55 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
08:32:55 INFO - Using env: {'DISPLAY': ':0',
08:32:55 INFO - 'HOME': '/home/cltbld',
08:32:55 INFO - 'LANG': 'en_US.UTF-8',
08:32:55 INFO - 'LANGUAGE': 'en_US:en',
08:32:55 INFO - 'LOGNAME': 'cltbld',
08:32:55 INFO - 'MAIL': '/var/mail/cltbld',
08:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:55 INFO - 'MOZ_NO_REMOTE': '1',
08:32:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:55 INFO - 'NO_EM_RESTART': '1',
08:32:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:55 INFO - 'PWD': '/builds/slave/test',
08:32:55 INFO - 'SHELL': '/bin/bash',
08:32:55 INFO - 'SHLVL': '1',
08:32:55 INFO - 'TERM': 'linux',
08:32:55 INFO - 'TMOUT': '86400',
08:32:55 INFO - 'USER': 'cltbld',
08:32:55 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:55 INFO - '_': '/tools/buildbot/bin/python'}
08:32:55 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:55 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))
08:32:55 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))
08:32:55 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))
08:32:55 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))
08:32:55 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))
08:32:55 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))
08:32:55 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))
08:32:55 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))
08:32:55 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))
08:32:55 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
08:32:56 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:32:57 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
08:32:57 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))
08:32:57 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))
08:32:57 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))
08:32:57 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))
08:32:58 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
08:32:58 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:32:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
08:32:59 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:33:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
08:33:00 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:33:01 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
08:33:01 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
08:33:02 INFO - Found existing installation: requests 1.2.3
08:33:02 INFO - Uninstalling requests-1.2.3:
08:33:02 INFO - Successfully uninstalled requests-1.2.3
08:33:02 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
08:33:02 INFO - Return code: 0
08:33:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
08:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13bd2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1595a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1738930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, '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': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956', '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
08:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
08:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
08:33:02 INFO - Using env: {'DISPLAY': ':0',
08:33:02 INFO - 'HOME': '/home/cltbld',
08:33:02 INFO - 'LANG': 'en_US.UTF-8',
08:33:02 INFO - 'LANGUAGE': 'en_US:en',
08:33:02 INFO - 'LOGNAME': 'cltbld',
08:33:02 INFO - 'MAIL': '/var/mail/cltbld',
08:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:02 INFO - 'MOZ_NO_REMOTE': '1',
08:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:02 INFO - 'NO_EM_RESTART': '1',
08:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:02 INFO - 'PWD': '/builds/slave/test',
08:33:02 INFO - 'SHELL': '/bin/bash',
08:33:02 INFO - 'SHLVL': '1',
08:33:02 INFO - 'TERM': 'linux',
08:33:02 INFO - 'TMOUT': '86400',
08:33:02 INFO - 'USER': 'cltbld',
08:33:02 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:02 INFO - '_': '/tools/buildbot/bin/python'}
08:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
08:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
08:33:02 INFO - Return code: 0
08:33:02 INFO - Running post-action listener: _resource_record_post_action
08:33:02 INFO - Running post-action listener: _start_resource_monitoring
08:33:02 INFO - Starting resource monitoring.
08:33:02 INFO - [mozharness: 2017-12-12 16:33:02.884480Z] Finished create-virtualenv step (success)
08:33:02 INFO - [mozharness: 2017-12-12 16:33:02.884992Z] Running install step.
08:33:02 INFO - Running pre-action listener: _resource_record_pre_action
08:33:02 INFO - Running main action method: install
08:33:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:33:03 INFO - Reading from file tmpfile_stdout
08:33:03 INFO - Reading from file tmpfile_stderr
08:33:03 INFO - Detecting whether we're running mozinstall >=1.0...
08:33:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
08:33:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
08:33:03 INFO - Reading from file tmpfile_stdout
08:33:03 INFO - Output received:
08:33:03 INFO - Usage: mozinstall [options] installer
08:33:03 INFO - Options:
08:33:03 INFO - -h, --help show this help message and exit
08:33:03 INFO - -d DEST, --destination=DEST
08:33:03 INFO - Directory to install application into. [default:
08:33:03 INFO - "/builds/slave/test"]
08:33:03 INFO - --app=APP Application being installed. [default: firefox]
08:33:03 INFO - mkdir: /builds/slave/test/build/application
08:33:03 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']
08:33:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
08:33:18 INFO - Reading from file tmpfile_stdout
08:33:18 INFO - Output received:
08:33:18 INFO - /builds/slave/test/build/application/firefox/firefox
08:33:18 INFO - Running post-action listener: _resource_record_post_action
08:33:18 INFO - [mozharness: 2017-12-12 16:33:18.554678Z] Finished install step (success)
08:33:18 INFO - [mozharness: 2017-12-12 16:33:18.554920Z] Running setup-mitmproxy step.
08:33:18 INFO - Running pre-action listener: _resource_record_pre_action
08:33:18 INFO - Running main action method: setup_mitmproxy
08:33:18 INFO - Skipping: mitmproxy is not required
08:33:18 INFO - Running post-action listener: _resource_record_post_action
08:33:18 INFO - [mozharness: 2017-12-12 16:33:18.556015Z] Finished setup-mitmproxy step (success)
08:33:18 INFO - [mozharness: 2017-12-12 16:33:18.556300Z] Running run-tests step.
08:33:18 INFO - Running pre-action listener: _resource_record_pre_action
08:33:18 INFO - Running pre-action listener: _set_gcov_prefix
08:33:18 INFO - Running main action method: run_tests
08:33:18 WARNING - Try message not found.
08:33:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
08:33:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
08:33:18 INFO - Python 2.7.3
08:33:18 INFO - Return code: 0
08:33:18 INFO - grabbing minidump binary from tooltool
08:33:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:18 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 0x1735ad0>, '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 0x17369d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1736e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
08:33:18 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
08:33:18 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
08:33:18 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
08:33:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
08:33:18 INFO - Return code: 0
08:33:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
08:33:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
08:33:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
08:33:18 INFO - ENV: RUST_BACKTRACE is now full
08:33:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
08:33:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
08:33:18 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/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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
08:33:18 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/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-044 --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
08:33:18 INFO - Using env: {'DISPLAY': ':0',
08:33:18 INFO - 'HOME': '/home/cltbld',
08:33:18 INFO - 'LANG': 'en_US.UTF-8',
08:33:18 INFO - 'LANGUAGE': 'en_US:en',
08:33:18 INFO - 'LOGNAME': 'cltbld',
08:33:18 INFO - 'MAIL': '/var/mail/cltbld',
08:33:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:33:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:33:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:18 INFO - 'MOZ_NO_REMOTE': '1',
08:33:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:33:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:18 INFO - 'NO_EM_RESTART': '1',
08:33:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:18 INFO - 'PWD': '/builds/slave/test',
08:33:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:33:18 INFO - 'RUST_BACKTRACE': 'full',
08:33:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:33:18 INFO - 'SHELL': '/bin/bash',
08:33:18 INFO - 'SHLVL': '1',
08:33:18 INFO - 'TERM': 'linux',
08:33:18 INFO - 'TMOUT': '86400',
08:33:18 INFO - 'USER': 'cltbld',
08:33:18 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:33:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:18 INFO - '_': '/tools/buildbot/bin/python'}
08:33:18 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/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-044', '--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
08:33:19 INFO - mozversion application_buildid: 20171212151548
08:33:19 INFO - mozversion application_changeset: 96355d26b932219a8081bfe21ee80048e12ce274
08:33:19 INFO - mozversion application_display_name: Firefox
08:33:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
08:33:19 INFO - mozversion application_name: Firefox
08:33:19 INFO - mozversion application_remotingname: firefox
08:33:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
08:33:19 INFO - mozversion application_vendor: Mozilla
08:33:19 INFO - mozversion application_version: 58.0
08:33:19 INFO - mozversion platform_buildid: 20171212151548
08:33:19 INFO - mozversion platform_changeset: 96355d26b932219a8081bfe21ee80048e12ce274
08:33:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
08:33:19 INFO - mozversion platform_version: 58.0
08:33:19 INFO - using testdate: 1513096398
08:33:19 INFO - actual date: 1513096398
08:33:19 INFO - starting webserver on 'localhost:36092'
08:33:19 INFO - SUITE-START | Running 3 tests
08:33:19 INFO - TEST-START | basic_compositor_video
08:33:19 INFO - Initialising browser for basic_compositor_video test...
08:33:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36092/getInfo.html -profile /tmp/tmpGGofKt/profile
08:33:19 INFO - TEST-INFO | started process 31983 (/builds/slave/test/build/application/firefox/firefox http://localhost:36092/getInfo.html)
08:33:24 INFO - TEST-INFO | 31983: exit 0
08:33:24 INFO - Browser initialized.
08:33:24 INFO - Running cycle 1/1 for basic_compositor_video test...
08:33:24 INFO - Using env: {'DISPLAY': ':0',
08:33:24 INFO - 'HOME': '/home/cltbld',
08:33:24 INFO - 'JSGC_DISABLE_POISONING': '1',
08:33:24 INFO - 'LANG': 'en_US.UTF-8',
08:33:24 INFO - 'LANGUAGE': 'en_US:en',
08:33:24 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:33:24 INFO - 'LOGNAME': 'cltbld',
08:33:24 INFO - 'MAIL': '/var/mail/cltbld',
08:33:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:33:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:33:24 INFO - 'MOZ_CRASHREPORTER': '1',
08:33:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:24 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:33:24 INFO - 'MOZ_NO_REMOTE': '1',
08:33:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:33:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:24 INFO - 'NO_EM_RESTART': '1',
08:33:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:24 INFO - 'PWD': '/builds/slave/test',
08:33:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:33:24 INFO - 'RUST_BACKTRACE': 'full',
08:33:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:33:24 INFO - 'SHELL': '/bin/bash',
08:33:24 INFO - 'SHLVL': '1',
08:33:24 INFO - 'TERM': 'linux',
08:33:24 INFO - 'TMOUT': '86400',
08:33:24 INFO - 'USER': 'cltbld',
08:33:24 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:33:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:24 INFO - '_': '/tools/buildbot/bin/python'}
08:33:24 INFO - TEST-INFO | started process 32219 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGGofKt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
08:33:25 INFO - PID 32219 |
08:33:25 INFO - PID 32219 | (/builds/slave/test/build/application/firefox/firefox:32270): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:33:25 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6828631519910264 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6653330557868444 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.701951219512195 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6865430016863405 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9957390146471363 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8258030592734236 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.4368098510882006 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.344841137123746 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.800808000000002 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.540540166204981 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.104511784511775 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 9.526666666666678 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991538461538457 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.475654450261773 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.608046218487411 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90860119047617 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451125954198499 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.236320224719073 ms/frame
08:34:11 INFO - PID 32219 |
08:34:11 INFO - PID 32219 | Cycle 1(1): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:34:57 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.691324689966178 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6704674457429047 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.756876463700234 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7041524701873936 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9581332453825873 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8479999999999985 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.421305587229191 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.362487394957984 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.567393320964751 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.650663841807917 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 11.029889705882356 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 9.806053921568633 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239588014981264 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527052631578945 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.609810924369725 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.980119760479043 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.366344696969684 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.116638888888904 ms/frame
08:34:57 INFO - PID 32219 |
08:34:57 INFO - PID 32219 | Cycle 1(2): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:35:43 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6829192372406057 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669070058381985 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.730432525951557 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7323203463203471 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 3.7929962073324908 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9935029940119775 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.5138348946135816 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.378868243243245 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.567875695732837 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.556805555555558 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.276267123287678 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 9.805245098039217 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.279736842105276 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.58439153439152 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.712203389830515 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.194847560975576 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.411121673003802 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.8148108108108 ms/frame
08:35:43 INFO - PID 32219 |
08:35:43 INFO - PID 32219 | Cycle 1(3): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:36:29 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6867818999437885 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670291666666666 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7741720875221763 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.738258036490009 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 3.901469440832249 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.042424242424242 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.433243707093822 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3447575250836135 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693538899430738 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5891340782122905 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.239658703071663 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 10.154162436548237 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.27892857142857 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.813513513513513 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.60922268907561 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.271165644171806 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.767176470588225 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17578212290505 ms/frame
08:36:29 INFO - PID 32219 |
08:36:29 INFO - PID 32219 | Cycle 1(4): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:37:15 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6817741031390134 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6690575479566303 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.737321945570353 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.695758474576272 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 4.149522821576763 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9609405940594087 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.433649885583522 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3628739495798317 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.651741996233523 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.588645251396639 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.136402027027026 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 10.309278350515465 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.116981481481465 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.63922872340427 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.87787553648071 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.195640243902433 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.85873517786562 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.30285310734461 ms/frame
08:37:15 INFO - PID 32219 |
08:37:15 INFO - PID 32219 | Cycle 1(5): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:38:02 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6784507829977628 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.666983333333333 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7483420745920744 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7470567685589529 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093485675306957 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.93005893909627 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.4092727272727292 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3454682274247496 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.338514234875443 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.437690217391304 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 9.871217105263165 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 9.902351485148504 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.23597378277154 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.643244680851065 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.662025316455702 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.500343750000003 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674202334630337 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.237752808988763 ms/frame
08:38:02 INFO - PID 32219 |
08:38:02 INFO - PID 32219 | Cycle 1(6): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:38:48 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6779865771812081 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674458333333337 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7183247422680417 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7232256675279922 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1329476584022045 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.098924180327868 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.547009456264776 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.496870629370628 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.536586715867156 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587653631284919 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.348758620689653 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 10.157563451776655 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.990128205128219 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640186170212745 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.046608695652147 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054698795180734 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.494616858237553 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11274999999999 ms/frame
08:38:48 INFO - PID 32219 |
08:38:48 INFO - PID 32219 | Cycle 1(7): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:39:34 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6799804031354983 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6690700583819846 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7482255244755245 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7095726495726493 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 4.156634349030471 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.15161825726141 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.4681965317919072 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.385380710659896 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.588258845437617 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572242339832862 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.067802013422812 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 10.582328042328049 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.15494423791823 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.698823529411767 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.766148936170206 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.982395209580824 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.767431372549018 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.237640449438219 ms/frame
08:39:34 INFO - PID 32219 |
08:39:34 INFO - PID 32219 | Cycle 1(8): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:40:20 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6763044692737432 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683611342785656 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7263032220943617 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.687852320675105 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9217516339869296 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9926447105788396 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.4333066361556055 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3562248322147674 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683892045454548 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5116666666666685 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.27453767123288 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 9.952014925373133 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.912327272727284 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.471125654450265 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.661561181434573 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.503593750000027 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.4512786259542 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.81429729729728 ms/frame
08:40:20 INFO - PID 32219 |
08:40:20 INFO - PID 32219 | Cycle 1(9): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:41:06 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682647223780146 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669958263772955 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7817102137767225 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.72644089732528 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115939643347051 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.176022964509392 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.425679223744295 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.355889261744961 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.671238185255197 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.495068681318686 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.679733096085412 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 10.001724999999988 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.236011235955054 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.929890710382503 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.401260330578495 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.048222891566294 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49787356321839 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.30367231638418 ms/frame
08:41:06 INFO - PID 32219 |
08:41:06 INFO - PID 32219 | Cycle 1(10): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:41:52 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6898367117117117 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.666053288925895 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7322661662817551 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7093760683760686 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0988456284153 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.067306910569105 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.401417233560092 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3795270270270277 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.485201096892137 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510330578512397 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 10.6010777385159 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 10.420234375000007 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.873079710144918 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.368316062176161 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.711822033898327 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.125696969696957 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542288461538472 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.049972375690597 ms/frame
08:41:52 INFO - PID 32219 |
08:41:52 INFO - PID 32219 | Cycle 1(11): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:42:38 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_startup = 1.687710911136108 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6712155388471175 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7235381964388283 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7131592465753418 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_startup = 4.133278236914601 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.049473684210527 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_startup = 3.6193365500603116 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5913824057450654 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6397650375939845 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572339832869084 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_startup = 9.288359133126933 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.480p.60fps.webm_scale_2_inclip = 10.050201005025134 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.070571955719544 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.587010582010619 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.768914893617021 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.764941176470593 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.450935114503832 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932568306010909 ms/frame
08:42:38 INFO - PID 32219 |
08:42:38 INFO - PID 32219 | Cycle 1(12): loaded http://localhost:36092/tests/video/video_playback.html (next: http://localhost:36092/tests/video/video_playback.html)
08:42:39 INFO - PID 32219 | __start_tp_report
08:42:39 INFO - PID 32219 | _x_x_mozilla_page_load
08:42:39 INFO - PID 32219 | _x_x_mozilla_page_load_details
08:42:39 INFO - PID 32219 | |i|pagename|runs|
08:42:39 INFO - PID 32219 | |0;240p.120fps.mp4_scale_1_startup;1.6828631519910264;1.691324689966178;1.6829192372406057;1.6867818999437885;1.6817741031390134;1.6784507829977628;1.6779865771812081;1.6799804031354983;1.6763044692737432;1.682647223780146;1.6898367117117117;1.687710911136108
08:42:39 INFO - PID 32219 | |1;240p.120fps.mp4_scale_1_inclip;1.6653330557868444;1.6704674457429047;1.669070058381985;1.6670291666666666;1.6690575479566303;1.666983333333333;1.6674458333333337;1.6690700583819846;1.6683611342785656;1.669958263772955;1.666053288925895;1.6712155388471175
08:42:39 INFO - PID 32219 | |2;240p.120fps.mp4_scale_1.1_startup;1.701951219512195;1.756876463700234;1.730432525951557;1.7741720875221763;1.737321945570353;1.7483420745920744;1.7183247422680417;1.7482255244755245;1.7263032220943617;1.7817102137767225;1.7322661662817551;1.7235381964388283
08:42:39 INFO - PID 32219 | |3;240p.120fps.mp4_scale_1.1_inclip;1.6865430016863405;1.7041524701873936;1.7323203463203471;1.738258036490009;1.695758474576272;1.7470567685589529;1.7232256675279922;1.7095726495726493;1.687852320675105;1.72644089732528;1.7093760683760686;1.7131592465753418
08:42:39 INFO - PID 32219 | |4;240p.120fps.mp4_scale_2_startup;3.9957390146471363;3.9581332453825873;3.7929962073324908;3.901469440832249;4.149522821576763;4.093485675306957;4.1329476584022045;4.156634349030471;3.9217516339869296;4.115939643347051;4.0988456284153;4.133278236914601
08:42:39 INFO - PID 32219 | |5;240p.120fps.mp4_scale_2_inclip;3.8258030592734236;3.8479999999999985;3.9935029940119775;4.042424242424242;3.9609405940594087;3.93005893909627;4.098924180327868;4.15161825726141;3.9926447105788396;4.176022964509392;4.067306910569105;4.049473684210527
08:42:39 INFO - PID 32219 | |6;480p.60fps.webm_scale_1_startup;3.4368098510882006;3.421305587229191;3.5138348946135816;3.433243707093822;3.433649885583522;3.4092727272727292;3.547009456264776;3.4681965317919072;3.4333066361556055;3.425679223744295;3.401417233560092;3.6193365500603116
08:42:39 INFO - PID 32219 | |7;480p.60fps.webm_scale_1_inclip;3.344841137123746;3.362487394957984;3.378868243243245;3.3447575250836135;3.3628739495798317;3.3454682274247496;3.496870629370628;3.385380710659896;3.3562248322147674;3.355889261744961;3.3795270270270277;3.5913824057450654
08:42:39 INFO - PID 32219 | |8;480p.60fps.webm_scale_1.1_startup;4.800808000000002;5.567393320964751;5.567875695732837;5.693538899430738;5.651741996233523;5.338514234875443;5.536586715867156;5.588258845437617;5.683892045454548;5.671238185255197;5.485201096892137;5.6397650375939845
08:42:39 INFO - PID 32219 | |9;480p.60fps.webm_scale_1.1_inclip;5.540540166204981;5.650663841807917;5.556805555555558;5.5891340782122905;5.588645251396639;5.437690217391304;5.587653631284919;5.572242339832862;5.5116666666666685;5.495068681318686;5.510330578512397;5.572339832869084
08:42:39 INFO - PID 32219 | |10;480p.60fps.webm_scale_2_startup;10.104511784511775;11.029889705882356;10.276267123287678;10.239658703071663;10.136402027027026;9.871217105263165;10.348758620689653;10.067802013422812;10.27453767123288;10.679733096085412;10.6010777385159;9.288359133126933
08:42:39 INFO - PID 32219 | |11;480p.60fps.webm_scale_2_inclip;9.526666666666678;9.806053921568633;9.805245098039217;10.154162436548237;10.309278350515465;9.902351485148504;10.157563451776655;10.582328042328049;9.952014925373133;10.001724999999988;10.420234375000007;10.050201005025134
08:42:39 INFO - PID 32219 | |12;1080p.60fps.mp4_scale_1_startup;10.991538461538457;11.239588014981264;11.279736842105276;11.27892857142857;11.116981481481465;11.23597378277154;10.990128205128219;11.15494423791823;10.912327272727284;11.236011235955054;10.873079710144918;11.070571955719544
08:42:39 INFO - PID 32219 | |13;1080p.60fps.mp4_scale_1_inclip;10.475654450261773;10.527052631578945;10.58439153439152;10.813513513513513;10.63922872340427;10.643244680851065;10.640186170212745;10.698823529411767;10.471125654450265;10.929890710382503;10.368316062176161;10.587010582010619
08:42:39 INFO - PID 32219 | |14;1080p.60fps.mp4_scale_1.1_startup;12.608046218487411;12.609810924369725;12.712203389830515;12.60922268907561;12.87787553648071;12.662025316455702;13.046608695652147;12.766148936170206;12.661561181434573;12.401260330578495;12.711822033898327;12.768914893617021
08:42:39 INFO - PID 32219 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.90860119047617;11.980119760479043;12.194847560975576;12.271165644171806;12.195640243902433;12.500343750000003;12.054698795180734;11.982395209580824;12.503593750000027;12.048222891566294;12.125696969696957;11.764941176470593
08:42:39 INFO - PID 32219 | |16;1080p.60fps.mp4_scale_2_startup;11.451125954198499;11.366344696969684;11.411121673003802;11.767176470588225;11.85873517786562;11.674202334630337;11.494616858237553;11.767431372549018;11.4512786259542;11.49787356321839;11.542288461538472;11.450935114503832
08:42:39 INFO - PID 32219 | |17;1080p.60fps.mp4_scale_2_inclip;11.236320224719073;11.116638888888904;10.8148108108108;11.17578212290505;11.30285310734461;11.237752808988763;11.11274999999999;11.237640449438219;10.81429729729728;11.30367231638418;11.049972375690597;10.932568306010909
08:42:39 INFO - PID 32219 | __end_tp_report
08:42:39 INFO - PID 32219 | __start_cc_report
08:42:39 INFO - PID 32219 | _x_x_mozilla_cycle_collect,553
08:42:39 INFO - PID 32219 | __end_cc_report
08:42:39 INFO - PID 32219 | __startTimestamp1513096959172__endTimestamp
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | ------- Summary: start -------
08:42:39 INFO - PID 32219 | Number of tests: 18
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.01
08:42:39 INFO - PID 32219 | 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
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#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
08:42:39 INFO - PID 32219 | 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
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.74 stddev:0.02 (1.3%) stddev-sans-first:0.02
08:42:39 INFO - PID 32219 | Values: 1.7 1.8 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.8 1.7 1.7
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.71 Median:1.72 stddev:0.02 (1.1%) stddev-sans-first:0.02
08:42:39 INFO - PID 32219 | 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
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.04 Median:4.11 stddev:0.12 (2.9%) stddev-sans-first:0.12
08:42:39 INFO - PID 32219 | Values: 4.0 4.0 3.8 3.9 4.1 4.1 4.1 4.2 3.9 4.1 4.1 4.1
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.01 Median:4.05 stddev:0.11 (2.7%) stddev-sans-first:0.10
08:42:39 INFO - PID 32219 | Values: 3.8 3.8 4.0 4.0 4.0 3.9 4.1 4.2 4.0 4.2 4.1 4.0
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.46 Median:3.44 stddev:0.07 (1.9%) stddev-sans-first:0.07
08:42:39 INFO - PID 32219 | Values: 3.4 3.4 3.5 3.4 3.4 3.4 3.5 3.5 3.4 3.4 3.4 3.6
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.39 Median:3.37 stddev:0.08 (2.2%) stddev-sans-first:0.08
08:42:39 INFO - PID 32219 | Values: 3.3 3.4 3.4 3.3 3.4 3.3 3.5 3.4 3.4 3.4 3.4 3.6
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.52 Median:5.61 stddev:0.25 (4.4%) stddev-sans-first:0.10
08:42:39 INFO - PID 32219 | Values: 4.8 5.6 5.6 5.7 5.7 5.3 5.5 5.6 5.7 5.7 5.5 5.6
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.55 Median:5.57 stddev:0.06 (1.0%) stddev-sans-first:0.06
08:42:39 INFO - PID 32219 | Values: 5.5 5.7 5.6 5.6 5.6 5.4 5.6 5.6 5.5 5.5 5.5 5.6
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.24 Median:10.47 stddev:0.43 (4.1%) stddev-sans-first:0.45
08:42:39 INFO - PID 32219 | Values: 10.1 11.0 10.3 10.2 10.1 9.9 10.3 10.1 10.3 10.7 10.6 9.3
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.06 Median:10.05 stddev:0.29 (2.9%) stddev-sans-first:0.25
08:42:39 INFO - PID 32219 | Values: 9.5 9.8 9.8 10.2 10.3 9.9 10.2 10.6 10.0 10.0 10.4 10.1
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.11 Median:11.20 stddev:0.15 (1.3%) stddev-sans-first:0.15
08:42:39 INFO - PID 32219 | Values: 11.0 11.2 11.3 11.3 11.1 11.2 11.0 11.2 10.9 11.2 10.9 11.1
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.61 Median:10.64 stddev:0.15 (1.4%) stddev-sans-first:0.15
08:42:39 INFO - PID 32219 | Values: 10.5 10.5 10.6 10.8 10.6 10.6 10.6 10.7 10.5 10.9 10.4 10.6
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.70 Median:12.71 stddev:0.16 (1.3%) stddev-sans-first:0.16
08:42:39 INFO - PID 32219 | Values: 12.6 12.6 12.7 12.6 12.9 12.7 13.0 12.8 12.7 12.4 12.7 12.8
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.13 Median:12.16 stddev:0.22 (1.8%) stddev-sans-first:0.22
08:42:39 INFO - PID 32219 | Values: 11.9 12.0 12.2 12.3 12.2 12.5 12.1 12.0 12.5 12.0 12.1 11.8
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.56 Median:11.52 stddev:0.16 (1.4%) stddev-sans-first:0.17
08:42:39 INFO - PID 32219 | Values: 11.5 11.4 11.4 11.8 11.9 11.7 11.5 11.8 11.5 11.5 11.5 11.5
08:42:39 INFO - PID 32219 |
08:42:39 INFO - PID 32219 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.11 Median:11.21 stddev:0.18 (1.6%) stddev-sans-first:0.18
08:42:39 INFO - PID 32219 | Values: 11.2 11.1 10.8 11.2 11.3 11.2 11.1 11.2 10.8 11.3 11.0 10.9
08:42:39 INFO - PID 32219 | -------- Summary: end --------
08:42:39 INFO - PID 32219 |
08:42:39 INFO - TEST-INFO | 32219: exit 0
08:42:39 INFO - TEST-OK | basic_compositor_video | took 560741ms
08:42:39 INFO - TEST-START | glvideo
08:42:39 INFO - Initialising browser for glvideo test...
08:42:39 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36092/getInfo.html -profile /tmp/tmptBo9an/profile
08:42:39 INFO - TEST-INFO | started process 977 (/builds/slave/test/build/application/firefox/firefox http://localhost:36092/getInfo.html)
08:42:44 INFO - TEST-INFO | 977: exit 0
08:42:44 INFO - Browser initialized.
08:42:44 INFO - Running cycle 1/1 for glvideo test...
08:42:44 INFO - Using env: {'DISPLAY': ':0',
08:42:44 INFO - 'HOME': '/home/cltbld',
08:42:44 INFO - 'JSGC_DISABLE_POISONING': '1',
08:42:44 INFO - 'LANG': 'en_US.UTF-8',
08:42:44 INFO - 'LANGUAGE': 'en_US:en',
08:42:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:42:44 INFO - 'LOGNAME': 'cltbld',
08:42:44 INFO - 'MAIL': '/var/mail/cltbld',
08:42:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:42:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:42:44 INFO - 'MOZ_CRASHREPORTER': '1',
08:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:42:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:42:44 INFO - 'MOZ_NO_REMOTE': '1',
08:42:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:42:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:42:44 INFO - 'NO_EM_RESTART': '1',
08:42:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:42:44 INFO - 'PWD': '/builds/slave/test',
08:42:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:42:44 INFO - 'RUST_BACKTRACE': 'full',
08:42:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:42:44 INFO - 'SHELL': '/bin/bash',
08:42:44 INFO - 'SHLVL': '1',
08:42:44 INFO - 'TERM': 'linux',
08:42:44 INFO - 'TMOUT': '86400',
08:42:44 INFO - 'USER': 'cltbld',
08:42:44 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:42:44 INFO - '_': '/tools/buildbot/bin/python'}
08:42:44 INFO - TEST-INFO | started process 1228 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptBo9an/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:42:45 INFO - PID 1228 |
08:42:45 INFO - PID 1228 | (/builds/slave/test/build/application/firefox/firefox:1347): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:42:45 INFO - PID 1228 |
08:42:47 INFO - PID 1228 | [talos glvideo result] Mean tick time across 100 ticks: 7.1164499999999995 ms
08:42:47 INFO - PID 1228 | Cycle 1(1): loaded http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html)
08:42:48 INFO - PID 1228 | [talos glvideo result] Mean tick time across 100 ticks: 6.5355 ms
08:42:48 INFO - PID 1228 | Cycle 1(2): loaded http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html)
08:42:49 INFO - PID 1228 | [talos glvideo result] Mean tick time across 100 ticks: 6.517 ms
08:42:49 INFO - PID 1228 | Cycle 1(3): loaded http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html)
08:42:51 INFO - PID 1228 | [talos glvideo result] Mean tick time across 100 ticks: 6.65695 ms
08:42:51 INFO - PID 1228 | Cycle 1(4): loaded http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html)
08:42:52 INFO - PID 1228 | [talos glvideo result] Mean tick time across 100 ticks: 6.57115 ms
08:42:52 INFO - PID 1228 | Cycle 1(5): loaded http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36092/tests/webgl/benchmarks/video/video_upload.html)
08:42:52 INFO - PID 1228 | __start_tp_report
08:42:52 INFO - PID 1228 | _x_x_mozilla_page_load
08:42:52 INFO - PID 1228 | _x_x_mozilla_page_load_details
08:42:52 INFO - PID 1228 | |i|pagename|runs|
08:42:52 INFO - PID 1228 | |0;Mean tick time across 100 ticks: ;7.1164499999999995;6.5355;6.517;6.65695;6.57115
08:42:52 INFO - PID 1228 | __end_tp_report
08:42:52 INFO - PID 1228 | __start_cc_report
08:42:52 INFO - PID 1228 | _x_x_mozilla_cycle_collect,249
08:42:52 INFO - PID 1228 | __end_cc_report
08:42:52 INFO - PID 1228 | __startTimestamp1513096972729__endTimestamp
08:42:52 INFO - PID 1228 |
08:42:52 INFO - PID 1228 | ------- Summary: start -------
08:42:52 INFO - PID 1228 | Number of tests: 1
08:42:52 INFO - PID 1228 |
08:42:52 INFO - PID 1228 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.68 Median:6.57 stddev:0.25 (3.8%) stddev-sans-first:0.06
08:42:52 INFO - PID 1228 | Values: 7.1 6.5 6.5 6.7 6.6
08:42:52 INFO - PID 1228 | -------- Summary: end --------
08:42:52 INFO - PID 1228 |
08:42:53 INFO - PID 1228 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:42:53 INFO - TEST-INFO | 1228: exit 0
08:42:53 INFO - TEST-OK | glvideo | took 13594ms
08:42:53 INFO - TEST-START | displaylist_mutate
08:42:53 INFO - Initialising browser for displaylist_mutate test...
08:42:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36092/getInfo.html -profile /tmp/tmpyaViWh/profile
08:42:53 INFO - TEST-INFO | started process 1523 (/builds/slave/test/build/application/firefox/firefox http://localhost:36092/getInfo.html)
08:42:58 INFO - TEST-INFO | 1523: exit 0
08:42:58 INFO - Browser initialized.
08:42:58 INFO - Running cycle 1/1 for displaylist_mutate test...
08:42:58 INFO - Using env: {'DISPLAY': ':0',
08:42:58 INFO - 'HOME': '/home/cltbld',
08:42:58 INFO - 'JSGC_DISABLE_POISONING': '1',
08:42:58 INFO - 'LANG': 'en_US.UTF-8',
08:42:58 INFO - 'LANGUAGE': 'en_US:en',
08:42:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:42:58 INFO - 'LOGNAME': 'cltbld',
08:42:58 INFO - 'MAIL': '/var/mail/cltbld',
08:42:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:42:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:42:58 INFO - 'MOZ_CRASHREPORTER': '1',
08:42:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:42:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:42:58 INFO - 'MOZ_NO_REMOTE': '1',
08:42:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:42:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:42:58 INFO - 'NO_EM_RESTART': '1',
08:42:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:42:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:42:58 INFO - 'PWD': '/builds/slave/test',
08:42:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:42:58 INFO - 'RUST_BACKTRACE': 'full',
08:42:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:42:58 INFO - 'SHELL': '/bin/bash',
08:42:58 INFO - 'SHLVL': '1',
08:42:58 INFO - 'TERM': 'linux',
08:42:58 INFO - 'TMOUT': '86400',
08:42:58 INFO - 'USER': 'cltbld',
08:42:58 INFO - 'XDG_SESSION_COOKIE': '88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956',
08:42:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:42:58 INFO - '_': '/tools/buildbot/bin/python'}
08:42:58 INFO - TEST-INFO | started process 1755 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyaViWh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:42:59 INFO - PID 1755 |
08:42:59 INFO - PID 1755 | (/builds/slave/test/build/application/firefox/firefox:1805): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:42:59 INFO - PID 1755 |
08:43:08 INFO - PID 1755 | Cycle 1(1): loaded http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html)
08:43:17 INFO - PID 1755 | Cycle 1(2): loaded http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html)
08:43:26 INFO - PID 1755 | Cycle 1(3): loaded http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html)
08:43:35 INFO - PID 1755 | Cycle 1(4): loaded http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html)
08:43:44 INFO - PID 1755 | Cycle 1(5): loaded http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:36092/tests/layout/benchmarks/displaylist_mutate.html)
08:43:44 INFO - PID 1755 | __start_tp_report
08:43:44 INFO - PID 1755 | _x_x_mozilla_page_load
08:43:44 INFO - PID 1755 | _x_x_mozilla_page_load_details
08:43:44 INFO - PID 1755 | |i|pagename|runs|
08:43:44 INFO - PID 1755 | |0;/displaylist_mutate.html;8179.525;8236.89;8245.955000000002;8309.115;8207.78
08:43:44 INFO - PID 1755 | __end_tp_report
08:43:44 INFO - PID 1755 | __start_cc_report
08:43:44 INFO - PID 1755 | _x_x_mozilla_cycle_collect,205
08:43:44 INFO - PID 1755 | __end_cc_report
08:43:44 INFO - PID 1755 | __startTimestamp1513097024330__endTimestamp
08:43:44 INFO - PID 1755 |
08:43:44 INFO - PID 1755 | ------- Summary: start -------
08:43:44 INFO - PID 1755 | Number of tests: 1
08:43:44 INFO - PID 1755 |
08:43:44 INFO - PID 1755 | [#0] /displaylist_mutate.html Cycles:5 Average:8235.85 Median:8236.89 stddev:48.56 (0.6%) stddev-sans-first:42.68
08:43:44 INFO - PID 1755 | Values: 8179.5 8236.9 8246.0 8309.1 8207.8
08:43:44 INFO - PID 1755 | -------- Summary: end --------
08:43:44 INFO - PID 1755 |
08:43:44 INFO - PID 1755 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:43:44 INFO - TEST-INFO | 1755: exit 0
08:43:44 INFO - TEST-OK | displaylist_mutate | took 51621ms
08:43:44 INFO - SUITE-END | took 625s
08:43:45 INFO - Completed test suite (00:10:26)
08:43:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.590390352046127, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6828631519910264, 1.691324689966178, 1.6829192372406057, 1.6867818999437885, 1.6817741031390134, 1.6784507829977628, 1.6779865771812081, 1.6799804031354983, 1.6763044692737432, 1.682647223780146, 1.6898367117117117, 1.687710911136108], "value": 1.682647223780146, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6653330557868444, 1.6704674457429047, 1.669070058381985, 1.6670291666666666, 1.6690575479566303, 1.666983333333333, 1.6674458333333337, 1.6690700583819846, 1.6683611342785656, 1.669958263772955, 1.666053288925895, 1.6712155388471175], "value": 1.6690575479566303, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.701951219512195, 1.756876463700234, 1.730432525951557, 1.7741720875221763, 1.737321945570353, 1.7483420745920744, 1.7183247422680417, 1.7482255244755245, 1.7263032220943617, 1.7817102137767225, 1.7322661662817551, 1.7235381964388283], "value": 1.737321945570353, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6865430016863405, 1.7041524701873936, 1.7323203463203471, 1.738258036490009, 1.695758474576272, 1.7470567685589529, 1.7232256675279922, 1.7095726495726493, 1.687852320675105, 1.72644089732528, 1.7093760683760686, 1.7131592465753418], "value": 1.7131592465753418, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9957390146471363, 3.9581332453825873, 3.7929962073324908, 3.901469440832249, 4.149522821576763, 4.093485675306957, 4.1329476584022045, 4.156634349030471, 3.9217516339869296, 4.115939643347051, 4.0988456284153, 4.133278236914601], "value": 4.0988456284153, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.8258030592734236, 3.8479999999999985, 3.9935029940119775, 4.042424242424242, 3.9609405940594087, 3.93005893909627, 4.098924180327868, 4.15161825726141, 3.9926447105788396, 4.176022964509392, 4.067306910569105, 4.049473684210527], "value": 4.042424242424242, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4368098510882006, 3.421305587229191, 3.5138348946135816, 3.433243707093822, 3.433649885583522, 3.4092727272727292, 3.547009456264776, 3.4681965317919072, 3.4333066361556055, 3.425679223744295, 3.401417233560092, 3.6193365500603116], "value": 3.4333066361556055, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.344841137123746, 3.362487394957984, 3.378868243243245, 3.3447575250836135, 3.3628739495798317, 3.3454682274247496, 3.496870629370628, 3.385380710659896, 3.3562248322147674, 3.355889261744961, 3.3795270270270277, 3.5913824057450654], "value": 3.3628739495798317, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.800808000000002, 5.567393320964751, 5.567875695732837, 5.693538899430738, 5.651741996233523, 5.338514234875443, 5.536586715867156, 5.588258845437617, 5.683892045454548, 5.671238185255197, 5.485201096892137, 5.6397650375939845], "value": 5.588258845437617, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.540540166204981, 5.650663841807917, 5.556805555555558, 5.5891340782122905, 5.588645251396639, 5.437690217391304, 5.587653631284919, 5.572242339832862, 5.5116666666666685, 5.495068681318686, 5.510330578512397, 5.572339832869084], "value": 5.572242339832862, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.104511784511775, 11.029889705882356, 10.276267123287678, 10.239658703071663, 10.136402027027026, 9.871217105263165, 10.348758620689653, 10.067802013422812, 10.27453767123288, 10.679733096085412, 10.6010777385159, 9.288359133126933], "value": 10.27453767123288, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.526666666666678, 9.806053921568633, 9.805245098039217, 10.154162436548237, 10.309278350515465, 9.902351485148504, 10.157563451776655, 10.582328042328049, 9.952014925373133, 10.001724999999988, 10.420234375000007, 10.050201005025134], "value": 10.050201005025134, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.991538461538457, 11.239588014981264, 11.279736842105276, 11.27892857142857, 11.116981481481465, 11.23597378277154, 10.990128205128219, 11.15494423791823, 10.912327272727284, 11.236011235955054, 10.873079710144918, 11.070571955719544], "value": 11.15494423791823, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.475654450261773, 10.527052631578945, 10.58439153439152, 10.813513513513513, 10.63922872340427, 10.643244680851065, 10.640186170212745, 10.698823529411767, 10.471125654450265, 10.929890710382503, 10.368316062176161, 10.587010582010619], "value": 10.63922872340427, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.608046218487411, 12.609810924369725, 12.712203389830515, 12.60922268907561, 12.87787553648071, 12.662025316455702, 13.046608695652147, 12.766148936170206, 12.661561181434573, 12.401260330578495, 12.711822033898327, 12.768914893617021], "value": 12.711822033898327, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.90860119047617, 11.980119760479043, 12.194847560975576, 12.271165644171806, 12.195640243902433, 12.500343750000003, 12.054698795180734, 11.982395209580824, 12.503593750000027, 12.048222891566294, 12.125696969696957, 11.764941176470593], "value": 12.125696969696957, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.451125954198499, 11.366344696969684, 11.411121673003802, 11.767176470588225, 11.85873517786562, 11.674202334630337, 11.494616858237553, 11.767431372549018, 11.4512786259542, 11.49787356321839, 11.542288461538472, 11.450935114503832], "value": 11.49787356321839, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.236320224719073, 11.116638888888904, 10.8148108108108, 11.17578212290505, 11.30285310734461, 11.237752808988763, 11.11274999999999, 11.237640449438219, 10.81429729729728, 11.30367231638418, 11.049972375690597, 10.932568306010909], "value": 11.116638888888904, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.1164499999999995, 6.5355, 6.517, 6.65695, 6.57115], "value": 6.553325, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8179.525, 8236.89, 8245.955000000002, 8309.115, 8207.78], "value": 8241.4225, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
08:43:45 INFO - Return code: 0
08:43:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
08:43:45 INFO - # TBPL SUCCESS #
08:43:45 INFO - Running post-action listener: _package_coverage_data
08:43:45 INFO - Running post-action listener: _resource_record_post_action
08:43:45 INFO - [mozharness: 2017-12-12 16:43:45.395146Z] Finished run-tests step (success)
08:43:45 INFO - Running post-run listener: _resource_record_post_run
08:43:45 INFO - Total resource usage - Wall time: 642s; CPU: 37.0%; Read bytes: 4096; Write bytes: 305328128; Read time: 20; Write time: 225936
08:43:45 INFO - TinderboxPrint: CPU usage
36.6%
08:43:45 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20
08:43:45 INFO - TinderboxPrint: I/O write bytes / time
305,328,128 / 225,936
08:43:45 INFO - TinderboxPrint: CPU idle
3,194.7 (63.4%)
08:43:45 INFO - TinderboxPrint: CPU system
314.3 (6.2%)
08:43:45 INFO - TinderboxPrint: CPU user
1,513.2 (30.0%)
08:43:45 INFO - TinderboxPrint: Swap in / out
0 / 0
08:43:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 8019968; Read time: 0; Write time: 2220
08:43:45 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
08:43:45 INFO - run-tests - Wall time: 627s; CPU: 37.0%; Read bytes: 4096; Write bytes: 297308160; Read time: 20; Write time: 223716
08:43:45 INFO - Running post-run listener: _upload_blobber_files
08:43:45 INFO - Blob upload gear active.
08:43:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
08:43:45 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
08:43:45 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']
08:43:45 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
08:43:45 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
08:43:45 INFO - SNIMissingWarning
08:43:45 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
08:43:45 INFO - InsecurePlatformWarning
08:43:46 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.)
08:43:46 INFO - SubjectAltNameWarning
08:43:46 INFO - (blobuploader) - INFO - Open directory for files ...
08:43:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
08:43:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:43:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:43:46 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
08:43:46 INFO - InsecurePlatformWarning
08:43:46 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.)
08:43:46 INFO - SubjectAltNameWarning
08:43:46 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
08:43:46 INFO - InsecurePlatformWarning
08:43:47 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
08:43:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:43:47 INFO - (blobuploader) - INFO - Done attempting.
08:43:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
08:43:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:43:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:43:47 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
08:43:47 INFO - InsecurePlatformWarning
08:43:47 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.)
08:43:47 INFO - SubjectAltNameWarning
08:43:47 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
08:43:47 INFO - InsecurePlatformWarning
08:43:48 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
08:43:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:43:48 INFO - (blobuploader) - INFO - Done attempting.
08:43:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
08:43:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:43:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:43:48 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
08:43:48 INFO - InsecurePlatformWarning
08:43:49 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.)
08:43:49 INFO - SubjectAltNameWarning
08:43:53 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
08:43:53 INFO - InsecurePlatformWarning
08:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:43:54 INFO - (blobuploader) - INFO - Done attempting.
08:43:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
08:43:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:43:54 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:43:54 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
08:43:54 INFO - InsecurePlatformWarning
08:43:54 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.)
08:43:54 INFO - SubjectAltNameWarning
08:43:54 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
08:43:54 INFO - InsecurePlatformWarning
08:43:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
08:43:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:43:55 INFO - (blobuploader) - INFO - Done attempting.
08:43:55 INFO - (blobuploader) - INFO - Iteration through files over.
08:43:55 INFO - Return code: 0
08:43:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
08:43:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
08:43:55 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/13888022236f611b88dd97e88a6fab06b5fc857d6ac01e4058d19e8d1d914b55fe664b2a79fdeb55b05d0ad79e1bf619f0a886bc37f49c7c0742ae47ad0fe45b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75dfd556e58b2a0b3f57691835368aac7e34c5a592888effc3978b523bdd7ff1a8cdbbda2b83ce777cad8e181d7ba6318b5f17402b3c8561bc5ed71483d650f0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8bc3ea3d5caa804843f0f535ee5c37604d93857fe98b18f46604acb439573c9deea7fb7325190b8e107ec7d2cfa350517a47a6e3a4be80a4a0437a990fc330bb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d845be48a236a1a88218f92713df456a34652e5fcca7f6893f1a4b8f67206aadaf3cbae83b8abeea3fd80d9b0a784c227e0eaba24da3bee014ad9c34127de056"}
08:43:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
08:43:55 INFO - Writing to file /builds/slave/test/properties/blobber_files
08:43:55 INFO - Contents:
08:43:55 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/13888022236f611b88dd97e88a6fab06b5fc857d6ac01e4058d19e8d1d914b55fe664b2a79fdeb55b05d0ad79e1bf619f0a886bc37f49c7c0742ae47ad0fe45b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75dfd556e58b2a0b3f57691835368aac7e34c5a592888effc3978b523bdd7ff1a8cdbbda2b83ce777cad8e181d7ba6318b5f17402b3c8561bc5ed71483d650f0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8bc3ea3d5caa804843f0f535ee5c37604d93857fe98b18f46604acb439573c9deea7fb7325190b8e107ec7d2cfa350517a47a6e3a4be80a4a0437a990fc330bb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d845be48a236a1a88218f92713df456a34652e5fcca7f6893f1a4b8f67206aadaf3cbae83b8abeea3fd80d9b0a784c227e0eaba24da3bee014ad9c34127de056"}
08:43:55 INFO - Running post-run listener: copy_logs_to_upload_dir
08:43:55 INFO - Copying logs to upload dir...
08:43:55 INFO - mkdir: /builds/slave/test/build/upload/logs
08:43:55 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=706.511498
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 46 secs) (at 2017-12-12 08:43:55.205826) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-12 08:43:55.209099) =========
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=88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/13888022236f611b88dd97e88a6fab06b5fc857d6ac01e4058d19e8d1d914b55fe664b2a79fdeb55b05d0ad79e1bf619f0a886bc37f49c7c0742ae47ad0fe45b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75dfd556e58b2a0b3f57691835368aac7e34c5a592888effc3978b523bdd7ff1a8cdbbda2b83ce777cad8e181d7ba6318b5f17402b3c8561bc5ed71483d650f0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8bc3ea3d5caa804843f0f535ee5c37604d93857fe98b18f46604acb439573c9deea7fb7325190b8e107ec7d2cfa350517a47a6e3a4be80a4a0437a990fc330bb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d845be48a236a1a88218f92713df456a34652e5fcca7f6893f1a4b8f67206aadaf3cbae83b8abeea3fd80d9b0a784c227e0eaba24da3bee014ad9c34127de056"}
build_url:https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011403
build_url: 'https://queue.taskcluster.net/v1/task/R60fzD-9QFGh1HypQ841WQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/13888022236f611b88dd97e88a6fab06b5fc857d6ac01e4058d19e8d1d914b55fe664b2a79fdeb55b05d0ad79e1bf619f0a886bc37f49c7c0742ae47ad0fe45b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/75dfd556e58b2a0b3f57691835368aac7e34c5a592888effc3978b523bdd7ff1a8cdbbda2b83ce777cad8e181d7ba6318b5f17402b3c8561bc5ed71483d650f0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8bc3ea3d5caa804843f0f535ee5c37604d93857fe98b18f46604acb439573c9deea7fb7325190b8e107ec7d2cfa350517a47a6e3a4be80a4a0437a990fc330bb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/d845be48a236a1a88218f92713df456a34652e5fcca7f6893f1a4b8f67206aadaf3cbae83b8abeea3fd80d9b0a784c227e0eaba24da3bee014ad9c34127de056"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-12 08:43:55.239073) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-12 08:43:55.239381) =========
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=88e9d754c8c9af9f7805d7f3000001bd-1513095697.834604-1873174956
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004574
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-12 08:43:55.322564) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-12 08:43:55.322889) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-12 08:43:55.323166) =========
========= Total master_lag: 0.20 =========