builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-088
starttime: 1515643760.37
results: success (0)
revision: 3e7bec52b1431c8dfd56c86ec5062514434fa788
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.366365) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.366840) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.367112) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.387448) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.387863) =========
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=9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2018-01-10 20:09:20-- 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% 46.3M=0s
2018-01-10 20:09:20 (46.3 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.207877
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.657958) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.658290) =========
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=9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.018961
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:20.697854) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2018-01-10 20:09:20.698182) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3e7bec52b1431c8dfd56c86ec5062514434fa788 --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 3e7bec52b1431c8dfd56c86ec5062514434fa788 --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=9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2018-01-10 20:09:20,757 truncating revision to first 12 chars
2018-01-10 20:09:20,757 Setting DEBUG logging.
2018-01-10 20:09:20,757 attempt 1/10
2018-01-10 20:09:20,757 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3e7bec52b143?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2018-01-10 20:09:21,432 unpacking tar archive at: mozilla-beta-3e7bec52b143/testing/mozharness/
program finished with exit code 0
elapsedTime=1.419888
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2018-01-10 20:09:22.139388) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:22.139710) =========
script_repo_revision: 3e7bec52b1431c8dfd56c86ec5062514434fa788
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:22.143195) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:22.143482) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2018-01-10 20:09:22.160962) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 44 secs) (at 2018-01-10 20:09:22.161343) =========
/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=9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
20:09:22 INFO - MultiFileLogger online at 20180110 20:09:22 in /builds/slave/test
20:09:22 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
20:09:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
20:09:22 INFO - {'append_to_log': False,
20:09:22 INFO - 'base_work_dir': '/builds/slave/test',
20:09:22 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
20:09:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
20:09:22 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
20:09:22 INFO - 'buildbot_json_path': 'buildprops.json',
20:09:22 INFO - 'code_coverage': False,
20:09:22 INFO - 'config_files': ('talos/linux_config.py',),
20:09:22 INFO - 'default_actions': ('clobber',
20:09:22 INFO - 'read-buildbot-config',
20:09:22 INFO - 'download-and-extract',
20:09:22 INFO - 'populate-webroot',
20:09:22 INFO - 'create-virtualenv',
20:09:22 INFO - 'install',
20:09:22 INFO - 'setup-mitmproxy',
20:09:22 INFO - 'run-tests'),
20:09:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
20:09:22 INFO - 'disable_ccov_upload': False,
20:09:22 INFO - 'disable_stylo': False,
20:09:22 INFO - 'download_minidump_stackwalk': True,
20:09:22 INFO - 'download_symbols': 'ondemand',
20:09:22 INFO - 'e10s': False,
20:09:22 INFO - 'enable_stylo': False,
20:09:22 INFO - 'enable_webrender': False,
20:09:22 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
20:09:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
20:09:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
20:09:22 INFO - 'gecko_profile': False,
20:09:22 INFO - 'gecko_profile_interval': 0,
20:09:22 INFO - 'installer_path': 'installer.exe',
20:09:22 INFO - 'jsd_code_coverage': False,
20:09:22 INFO - 'log_level': 'info',
20:09:22 INFO - 'log_name': 'talos',
20:09:22 INFO - 'log_to_console': True,
20:09:22 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
20:09:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
20:09:22 INFO - 'opt_config_files': (),
20:09:22 INFO - 'pip_index': False,
20:09:22 INFO - 'suite': 'g4-e10s',
20:09:22 INFO - 'system_bits': '32',
20:09:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
20:09:22 INFO - 'title': 'talos-linux64-ix-088',
20:09:22 INFO - 'tooltool_cache': '/builds/tooltool_cache',
20:09:22 INFO - 'use_talos_json': True,
20:09:22 INFO - 'verify': 'False',
20:09:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
20:09:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
20:09:22 INFO - 'work_dir': 'build'}
20:09:22 INFO - [mozharness: 2018-01-11 04:09:22.347305Z] Running clobber step.
20:09:22 INFO - Running pre-action listener: _resource_record_pre_action
20:09:22 INFO - Running main action method: clobber
20:09:22 INFO - rmtree: /builds/slave/test/build
20:09:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
20:09:23 INFO - Running post-action listener: _resource_record_post_action
20:09:23 INFO - [mozharness: 2018-01-11 04:09:23.375094Z] Finished clobber step (success)
20:09:23 INFO - [mozharness: 2018-01-11 04:09:23.375190Z] Running read-buildbot-config step.
20:09:23 INFO - Running pre-action listener: _resource_record_pre_action
20:09:23 INFO - Running main action method: read_buildbot_config
20:09:23 INFO - Using buildbot properties:
20:09:23 INFO - {
20:09:23 INFO - "project": "",
20:09:23 INFO - "product": "firefox",
20:09:23 INFO - "who": "ryanvm@gmail.com",
20:09:23 INFO - "installer_path": "public/build/target.tar.bz2",
20:09:23 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
20:09:23 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
20:09:23 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
20:09:23 INFO - "stage_platform": "linux64",
20:09:23 INFO - "basedir": "/builds/slave/test",
20:09:23 INFO - "buildnumber": 324,
20:09:23 INFO - "platform": "ubuntu64_hw",
20:09:23 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
20:09:23 INFO - "slavebuilddir": "test",
20:09:23 INFO - "taskId": "f68A32ttT0SCs0Cfk1PgCg",
20:09:23 INFO - "branch": "mozilla-beta",
20:09:23 INFO - "script_repo_revision": "production",
20:09:23 INFO - "revision": "3e7bec52b1431c8dfd56c86ec5062514434fa788",
20:09:23 INFO - "slavename": "talos-linux64-ix-088",
20:09:23 INFO - "repo_path": "releases/mozilla-beta"
20:09:23 INFO - }
20:09:23 INFO - Finding installer, test and symbols from parent task.
20:09:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f68A32ttT0SCs0Cfk1PgCg'}, attempt #1
20:09:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f68A32ttT0SCs0Cfk1PgCg'}, attempt #1
20:09:24 INFO - Task dependencies: E6HoyLRJSa68Rdpa2Q89Aw
20:09:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw'}, attempt #1
20:09:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2
20:09:24 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.test_packages.json
20:09:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.crashreporter-symbols.zip
20:09:24 INFO - Running post-action listener: _resource_record_post_action
20:09:24 INFO - [mozharness: 2018-01-11 04:09:24.746841Z] Finished read-buildbot-config step (success)
20:09:24 INFO - [mozharness: 2018-01-11 04:09:24.747058Z] Running download-and-extract step.
20:09:24 INFO - Running pre-action listener: _resource_record_pre_action
20:09:24 INFO - Running main action method: download_and_extract
20:09:24 INFO - mkdir: /builds/slave/test/build/tests
20:09:24 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')]}
20:09:24 INFO - trying https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.test_packages.json
20:09:24 INFO - Downloading https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
20:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
20:09:25 INFO - Downloaded 989 bytes.
20:09:25 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
20:09:25 INFO - Using the following test package requirements:
20:09:25 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
20:09:25 INFO - u'common': [u'target.common.tests.zip'],
20:09:25 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
20:09:25 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
20:09:25 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
20:09:25 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
20:09:25 INFO - u'mozbase': [u'target.common.tests.zip'],
20:09:25 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
20:09:25 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
20:09:25 INFO - u'web-platform': [u'target.common.tests.zip',
20:09:25 INFO - u'target.web-platform.tests.tar.gz'],
20:09:25 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
20:09:25 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
20:09:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.common.tests.zip
20:09:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.common.tests.zip'}, attempt #1
20:09:25 INFO - Fetch https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.common.tests.zip into memory
20:09:26 INFO - Content-Length response header: 58313981
20:09:26 INFO - Bytes received: 58313981
20:09:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.talos.tests.zip
20:09:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
20:09:37 INFO - Fetch https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.talos.tests.zip into memory
20:09:38 INFO - Content-Length response header: 18772095
20:09:38 INFO - Bytes received: 18772095
20:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:39 INFO - trying https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2
20:09:39 INFO - Downloading https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
20:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
20:09:41 INFO - Downloaded 54206531 bytes.
20:09:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2
20:09:41 INFO - mkdir: /builds/slave/test/properties
20:09:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
20:09:41 INFO - Writing to file /builds/slave/test/properties/build_url
20:09:41 INFO - Contents:
20:09:41 INFO - build_url:https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2
20:09:41 INFO - Running post-action listener: _resource_record_post_action
20:09:41 INFO - Running post-action listener: find_tests_for_verification
20:09:41 INFO - Running post-action listener: set_extra_try_arguments
20:09:41 INFO - [mozharness: 2018-01-11 04:09:41.539171Z] Finished download-and-extract step (success)
20:09:41 INFO - [mozharness: 2018-01-11 04:09:41.539280Z] Running populate-webroot step.
20:09:41 INFO - Running pre-action listener: _resource_record_pre_action
20:09:41 INFO - Running main action method: populate_webroot
20:09:41 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
20:09:41 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['tresize',
20:09:41 INFO - 'tcanvasmark']},
20:09:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
20:09:41 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['dromaeo_css',
20:09:41 INFO - 'kraken']},
20:09:41 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
20:09:41 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
20:09:41 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'tests': ['damp', 'tps']},
20:09:41 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['damp', 'tps']},
20:09:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
20:09:41 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['dromaeo_dom']},
20:09:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
20:09:41 INFO - 'glvideo',
20:09:41 INFO - 'displaylist_mutate']},
20:09:41 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['basic_compositor_video',
20:09:41 INFO - 'glvideo']},
20:09:41 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
20:09:41 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['ts_paint_webext',
20:09:41 INFO - 'tp5o_webext']},
20:09:41 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
20:09:41 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
20:09:41 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
20:09:41 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
20:09:41 INFO - 'talos_options': ['--mitmproxy',
20:09:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
20:09:41 INFO - '--firstNonBlankPaint'],
20:09:41 INFO - 'tests': ['tp6_google_heavy',
20:09:41 INFO - 'tp6_youtube_heavy',
20:09:41 INFO - 'tp6_amazon_heavy',
20:09:41 INFO - 'tp6_facebook_heavy']},
20:09:41 INFO - 'other-e10s': {'tests': ['a11yr',
20:09:41 INFO - 'ts_paint',
20:09:41 INFO - 'tpaint',
20:09:41 INFO - 'sessionrestore',
20:09:41 INFO - 'sessionrestore_many_windows',
20:09:41 INFO - 'sessionrestore_no_auto_restore',
20:09:41 INFO - 'tabpaint',
20:09:41 INFO - 'cpstartup']},
20:09:41 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['a11yr',
20:09:41 INFO - 'ts_paint',
20:09:41 INFO - 'tpaint',
20:09:41 INFO - 'sessionrestore',
20:09:41 INFO - 'sessionrestore_many_windows',
20:09:41 INFO - 'sessionrestore_no_auto_restore',
20:09:41 INFO - 'tabpaint',
20:09:41 INFO - 'cpstartup']},
20:09:41 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
20:09:41 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
20:09:41 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['perf_reftest_singletons']},
20:09:41 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['perf_reftest']},
20:09:41 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
20:09:41 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['speedometer']},
20:09:41 INFO - 'svgr-e10s': {'tests': ['tsvgx',
20:09:41 INFO - 'tsvgr_opacity',
20:09:41 INFO - 'tart',
20:09:41 INFO - 'tscrollx',
20:09:41 INFO - 'tsvg_static']},
20:09:41 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['tsvgx',
20:09:41 INFO - 'tsvgr_opacity',
20:09:41 INFO - 'tart',
20:09:41 INFO - 'tscrollx',
20:09:41 INFO - 'tsvg_static']},
20:09:41 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
20:09:41 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'talos_options': ['--disable-stylo'],
20:09:41 INFO - 'tests': ['tp5o']},
20:09:41 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
20:09:41 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
20:09:41 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
20:09:41 INFO - 'talos_options': ['--mitmproxy',
20:09:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
20:09:41 INFO - '--firstNonBlankPaint'],
20:09:41 INFO - 'tests': ['tp6_google',
20:09:41 INFO - 'tp6_youtube',
20:09:41 INFO - 'tp6_amazon',
20:09:41 INFO - 'tp6_facebook']},
20:09:41 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
20:09:41 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
20:09:41 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
20:09:41 INFO - 'talos_options': ['--disable-stylo',
20:09:41 INFO - '--mitmproxy',
20:09:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
20:09:41 INFO - '--firstNonBlankPaint'],
20:09:41 INFO - 'tests': ['tp6_google',
20:09:41 INFO - 'tp6_youtube',
20:09:41 INFO - 'tp6_amazon',
20:09:41 INFO - 'tp6_facebook']},
20:09:41 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
20:09:41 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
20:09:41 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
20:09:41 INFO - 'talos_options': ['--stylo-threads=1',
20:09:41 INFO - '--mitmproxy',
20:09:41 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
20:09:41 INFO - '--firstNonBlankPaint'],
20:09:41 INFO - 'tests': ['tp6_google',
20:09:41 INFO - 'tp6_youtube',
20:09:41 INFO - 'tp6_amazon',
20:09:41 INFO - 'tp6_facebook']},
20:09:41 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'talos_options': ['--xperf_path',
20:09:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
20:09:41 INFO - 'tests': ['tp5n']},
20:09:41 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
20:09:41 INFO - 'talos_options': ['--disable-stylo',
20:09:41 INFO - '--xperf_path',
20:09:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
20:09:41 INFO - 'tests': ['tp5n']}}}
20:09:41 INFO - Running post-action listener: _resource_record_post_action
20:09:41 INFO - [mozharness: 2018-01-11 04:09:41.615790Z] Finished populate-webroot step (success)
20:09:41 INFO - [mozharness: 2018-01-11 04:09:41.615889Z] Running create-virtualenv step.
20:09:41 INFO - Running pre-action listener: _resource_record_pre_action
20:09:41 INFO - Running main action method: create_virtualenv
20:09:41 INFO - Creating virtualenv /builds/slave/test/build/venv
20:09:41 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
20:09:41 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
20:09:41 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
20:09:42 INFO - Using real prefix '/usr'
20:09:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python
20:09:42 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
20:09:44 INFO - Installing setuptools, pip, wheel...done.
20:09:44 INFO - Return code: 0
20:09:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
20:09:44 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')]}
20:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:09:44 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')]}
20:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:09:44 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:09:44 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
20:09:44 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
20:09:44 INFO - Using env: {'DISPLAY': ':0',
20:09:44 INFO - 'HOME': '/home/cltbld',
20:09:44 INFO - 'LANG': 'en_US.UTF-8',
20:09:44 INFO - 'LANGUAGE': 'en_US:en',
20:09:44 INFO - 'LOGNAME': 'cltbld',
20:09:44 INFO - 'MAIL': '/var/mail/cltbld',
20:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:09:44 INFO - 'MOZ_NO_REMOTE': '1',
20:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:09:44 INFO - 'NO_EM_RESTART': '1',
20:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:09:44 INFO - 'PWD': '/builds/slave/test',
20:09:44 INFO - 'SHELL': '/bin/bash',
20:09:44 INFO - 'SHLVL': '1',
20:09:44 INFO - 'TERM': 'linux',
20:09:44 INFO - 'TMOUT': '86400',
20:09:44 INFO - 'USER': 'cltbld',
20:09:44 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:09:44 INFO - '_': '/tools/buildbot/bin/python'}
20:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple
20:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
20:09:44 INFO - Return code: 0
20:09:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
20:09:44 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')]}
20:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:09:44 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')]}
20:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:09:44 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:09:44 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
20:09:44 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
20:09:44 INFO - Using env: {'DISPLAY': ':0',
20:09:44 INFO - 'HOME': '/home/cltbld',
20:09:44 INFO - 'LANG': 'en_US.UTF-8',
20:09:44 INFO - 'LANGUAGE': 'en_US:en',
20:09:44 INFO - 'LOGNAME': 'cltbld',
20:09:44 INFO - 'MAIL': '/var/mail/cltbld',
20:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:09:44 INFO - 'MOZ_NO_REMOTE': '1',
20:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:09:44 INFO - 'NO_EM_RESTART': '1',
20:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:09:44 INFO - 'PWD': '/builds/slave/test',
20:09:44 INFO - 'SHELL': '/bin/bash',
20:09:44 INFO - 'SHLVL': '1',
20:09:44 INFO - 'TERM': 'linux',
20:09:44 INFO - 'TMOUT': '86400',
20:09:44 INFO - 'USER': 'cltbld',
20:09:44 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:09:44 INFO - '_': '/tools/buildbot/bin/python'}
20:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple
20:09:44 INFO - Collecting psutil>=3.1.1
20:09:46 INFO - Installing collected packages: psutil
20:09:46 INFO - Successfully installed psutil-3.1.1
20:09:46 INFO - Return code: 0
20:09:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
20:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:09:46 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:09:46 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
20:09:46 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
20:09:46 INFO - Using env: {'DISPLAY': ':0',
20:09:46 INFO - 'HOME': '/home/cltbld',
20:09:46 INFO - 'LANG': 'en_US.UTF-8',
20:09:46 INFO - 'LANGUAGE': 'en_US:en',
20:09:46 INFO - 'LOGNAME': 'cltbld',
20:09:46 INFO - 'MAIL': '/var/mail/cltbld',
20:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:09:46 INFO - 'MOZ_NO_REMOTE': '1',
20:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:09:46 INFO - 'NO_EM_RESTART': '1',
20:09:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:09:46 INFO - 'PWD': '/builds/slave/test',
20:09:46 INFO - 'SHELL': '/bin/bash',
20:09:46 INFO - 'SHLVL': '1',
20:09:46 INFO - 'TERM': 'linux',
20:09:46 INFO - 'TMOUT': '86400',
20:09:46 INFO - 'USER': 'cltbld',
20:09:46 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:09:46 INFO - '_': '/tools/buildbot/bin/python'}
20:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple
20:09:46 INFO - Collecting mozsystemmonitor==0.3
20:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
20:09:47 INFO - Installing collected packages: mozsystemmonitor
20:09:47 INFO - Successfully installed mozsystemmonitor-0.3
20:09:47 INFO - Return code: 0
20:09:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
20:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:09:47 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:09:47 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
20:09:47 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
20:09:47 INFO - Using env: {'DISPLAY': ':0',
20:09:47 INFO - 'HOME': '/home/cltbld',
20:09:47 INFO - 'LANG': 'en_US.UTF-8',
20:09:47 INFO - 'LANGUAGE': 'en_US:en',
20:09:47 INFO - 'LOGNAME': 'cltbld',
20:09:47 INFO - 'MAIL': '/var/mail/cltbld',
20:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:09:47 INFO - 'MOZ_NO_REMOTE': '1',
20:09:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:09:47 INFO - 'NO_EM_RESTART': '1',
20:09:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:09:47 INFO - 'PWD': '/builds/slave/test',
20:09:47 INFO - 'SHELL': '/bin/bash',
20:09:47 INFO - 'SHLVL': '1',
20:09:47 INFO - 'TERM': 'linux',
20:09:47 INFO - 'TMOUT': '86400',
20:09:47 INFO - 'USER': 'cltbld',
20:09:47 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:09:47 INFO - '_': '/tools/buildbot/bin/python'}
20:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple
20:09:48 INFO - Collecting jsonschema==2.5.1
20:09:49 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
20:09:49 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
20:09:50 INFO - Installing collected packages: functools32, jsonschema
20:09:50 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
20:09:50 INFO - Return code: 0
20:09:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
20:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:09:50 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:09:50 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
20:09:50 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
20:09:50 INFO - Using env: {'DISPLAY': ':0',
20:09:50 INFO - 'HOME': '/home/cltbld',
20:09:50 INFO - 'LANG': 'en_US.UTF-8',
20:09:50 INFO - 'LANGUAGE': 'en_US:en',
20:09:50 INFO - 'LOGNAME': 'cltbld',
20:09:50 INFO - 'MAIL': '/var/mail/cltbld',
20:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:09:50 INFO - 'MOZ_NO_REMOTE': '1',
20:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:09:50 INFO - 'NO_EM_RESTART': '1',
20:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:09:50 INFO - 'PWD': '/builds/slave/test',
20:09:50 INFO - 'SHELL': '/bin/bash',
20:09:50 INFO - 'SHLVL': '1',
20:09:50 INFO - 'TERM': 'linux',
20:09:50 INFO - 'TMOUT': '86400',
20:09:50 INFO - 'USER': 'cltbld',
20:09:50 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:09:50 INFO - '_': '/tools/buildbot/bin/python'}
20:09:50 INFO - Ignoring indexes: https://pypi.python.org/simple
20:09:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
20:09:50 INFO - Return code: 0
20:09:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
20:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:09:50 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:09:50 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
20:09:50 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
20:09:50 INFO - Using env: {'DISPLAY': ':0',
20:09:50 INFO - 'HOME': '/home/cltbld',
20:09:50 INFO - 'LANG': 'en_US.UTF-8',
20:09:50 INFO - 'LANGUAGE': 'en_US:en',
20:09:50 INFO - 'LOGNAME': 'cltbld',
20:09:50 INFO - 'MAIL': '/var/mail/cltbld',
20:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:09:50 INFO - 'MOZ_NO_REMOTE': '1',
20:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:09:50 INFO - 'NO_EM_RESTART': '1',
20:09:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:09:50 INFO - 'PWD': '/builds/slave/test',
20:09:50 INFO - 'SHELL': '/bin/bash',
20:09:50 INFO - 'SHLVL': '1',
20:09:50 INFO - 'TERM': 'linux',
20:09:50 INFO - 'TMOUT': '86400',
20:09:50 INFO - 'USER': 'cltbld',
20:09:50 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:09:50 INFO - '_': '/tools/buildbot/bin/python'}
20:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple
20:09:51 INFO - Collecting blobuploader==1.2.4
20:09:52 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
20:09:53 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
20:09:54 INFO - Installing collected packages: requests, docopt, blobuploader
20:09:54 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
20:09:54 INFO - Return code: 0
20:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv
20:09:54 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')]}
20:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:09:54 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')]}
20:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:09:54 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:09:54 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
20:09:54 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
20:09:54 INFO - Using env: {'DISPLAY': ':0',
20:09:54 INFO - 'HOME': '/home/cltbld',
20:09:54 INFO - 'LANG': 'en_US.UTF-8',
20:09:54 INFO - 'LANGUAGE': 'en_US:en',
20:09:54 INFO - 'LOGNAME': 'cltbld',
20:09:54 INFO - 'MAIL': '/var/mail/cltbld',
20:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:09:54 INFO - 'MOZ_NO_REMOTE': '1',
20:09:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:09:54 INFO - 'NO_EM_RESTART': '1',
20:09:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:09:54 INFO - 'PWD': '/builds/slave/test',
20:09:54 INFO - 'SHELL': '/bin/bash',
20:09:54 INFO - 'SHLVL': '1',
20:09:54 INFO - 'TERM': 'linux',
20:09:54 INFO - 'TMOUT': '86400',
20:09:54 INFO - 'USER': 'cltbld',
20:09:54 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:09:54 INFO - '_': '/tools/buildbot/bin/python'}
20:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple
20:09:55 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
20:09:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
20:09:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
20:09:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
20:09:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
20:09:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
20:09:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
20:09:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
20:09:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
20:09:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
20:09:56 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
20:09:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
20:09:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
20:09:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
20:09:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
20:09:57 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
20:09:58 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
20:09:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
20:09:58 INFO - Running setup.py install for manifestparser: started
20:09:58 INFO - Running setup.py install for manifestparser: finished with status 'done'
20:09:58 INFO - Running setup.py install for mozcrash: started
20:09:58 INFO - Running setup.py install for mozcrash: finished with status 'done'
20:09:58 INFO - Running setup.py install for mozdebug: started
20:09:58 INFO - Running setup.py install for mozdebug: finished with status 'done'
20:09:58 INFO - Running setup.py install for mozdevice: started
20:09:59 INFO - Running setup.py install for mozdevice: finished with status 'done'
20:09:59 INFO - Running setup.py install for mozfile: started
20:09:59 INFO - Running setup.py install for mozfile: finished with status 'done'
20:09:59 INFO - Running setup.py install for mozhttpd: started
20:09:59 INFO - Running setup.py install for mozhttpd: finished with status 'done'
20:09:59 INFO - Running setup.py install for mozinfo: started
20:09:59 INFO - Running setup.py install for mozinfo: finished with status 'done'
20:09:59 INFO - Running setup.py install for mozInstall: started
20:09:59 INFO - Running setup.py install for mozInstall: finished with status 'done'
20:09:59 INFO - Running setup.py install for mozleak: started
20:10:00 INFO - Running setup.py install for mozleak: finished with status 'done'
20:10:00 INFO - Running setup.py install for mozlog: started
20:10:00 INFO - Running setup.py install for mozlog: finished with status 'done'
20:10:00 INFO - Running setup.py install for moznetwork: started
20:10:00 INFO - Running setup.py install for moznetwork: finished with status 'done'
20:10:00 INFO - Running setup.py install for mozprocess: started
20:10:00 INFO - Running setup.py install for mozprocess: finished with status 'done'
20:10:00 INFO - Running setup.py install for mozprofile: started
20:10:01 INFO - Running setup.py install for mozprofile: finished with status 'done'
20:10:01 INFO - Running setup.py install for mozrunner: started
20:10:01 INFO - Running setup.py install for mozrunner: finished with status 'done'
20:10:01 INFO - Running setup.py install for mozscreenshot: started
20:10:01 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
20:10:01 INFO - Running setup.py install for moztest: started
20:10:01 INFO - Running setup.py install for moztest: finished with status 'done'
20:10:01 INFO - Running setup.py install for mozversion: started
20:10:02 INFO - Running setup.py install for mozversion: finished with status 'done'
20:10:02 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
20:10:02 INFO - Return code: 0
20:10:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv
20:10: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')]}
20:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:10: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')]}
20:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:10:02 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:10:02 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
20:10:02 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
20:10:02 INFO - Using env: {'DISPLAY': ':0',
20:10:02 INFO - 'HOME': '/home/cltbld',
20:10:02 INFO - 'LANG': 'en_US.UTF-8',
20:10:02 INFO - 'LANGUAGE': 'en_US:en',
20:10:02 INFO - 'LOGNAME': 'cltbld',
20:10:02 INFO - 'MAIL': '/var/mail/cltbld',
20:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:10:02 INFO - 'MOZ_NO_REMOTE': '1',
20:10:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:10:02 INFO - 'NO_EM_RESTART': '1',
20:10:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:10:02 INFO - 'PWD': '/builds/slave/test',
20:10:02 INFO - 'SHELL': '/bin/bash',
20:10:02 INFO - 'SHLVL': '1',
20:10:02 INFO - 'TERM': 'linux',
20:10:02 INFO - 'TMOUT': '86400',
20:10:02 INFO - 'USER': 'cltbld',
20:10:02 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:10:02 INFO - '_': '/tools/buildbot/bin/python'}
20:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple
20:10:02 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
20:10:03 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))
20:10:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
20:10:03 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))
20:10:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
20:10:03 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))
20:10:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
20:10:03 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))
20:10:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
20:10:03 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))
20:10:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
20:10:04 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))
20:10:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
20:10:04 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))
20:10:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
20:10:04 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))
20:10:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
20:10:04 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))
20:10:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
20:10:04 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))
20:10:04 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
20:10:05 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))
20:10:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
20:10:05 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))
20:10:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
20:10:05 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))
20:10:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
20:10:05 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))
20:10:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
20:10:05 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))
20:10:05 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
20:10:06 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))
20:10:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
20:10:06 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))
20:10:06 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
20:10:07 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
20:10:07 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))
20:10:07 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))
20:10:07 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))
20:10:07 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))
20:10:07 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))
20:10:07 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))
20:10:07 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
20:10:08 INFO - Installing collected packages: six, blessings
20:10:08 INFO - Successfully installed blessings-1.6 six-1.10.0
20:10:08 INFO - Return code: 0
20:10:08 INFO - Done creating virtualenv /builds/slave/test/build/venv.
20:10:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
20:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
20:10:09 INFO - Reading from file tmpfile_stdout
20:10:09 INFO - Reading from file tmpfile_stderr
20:10:09 INFO - Current package versions:
20:10:09 INFO - blessings == 1.6
20:10:09 INFO - blobuploader == 1.2.4
20:10:09 INFO - docopt == 0.6.1
20:10:09 INFO - functools32 == 3.2.3.post2
20:10:09 INFO - jsonschema == 2.5.1
20:10:09 INFO - manifestparser == 1.2
20:10:09 INFO - mozInstall == 1.14
20:10:09 INFO - mozcrash == 1.0
20:10:09 INFO - mozdebug == 0.1
20:10:09 INFO - mozdevice == 0.51
20:10:09 INFO - mozfile == 1.2
20:10:09 INFO - mozhttpd == 0.7
20:10:09 INFO - mozinfo == 0.10
20:10:09 INFO - mozleak == 0.1
20:10:09 INFO - mozlog == 3.5
20:10:09 INFO - moznetwork == 0.27
20:10:09 INFO - mozprocess == 0.25
20:10:09 INFO - mozprofile == 0.29
20:10:09 INFO - mozrunner == 6.13
20:10:09 INFO - mozscreenshot == 0.1
20:10:09 INFO - mozsystemmonitor == 0.3
20:10:09 INFO - moztest == 0.8
20:10:09 INFO - mozversion == 1.4
20:10:09 INFO - psutil == 3.1.1
20:10:09 INFO - requests == 1.2.3
20:10:09 INFO - six == 1.10.0
20:10:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv
20:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:10:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:10:09 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:10:09 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
20:10:09 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
20:10:09 INFO - Using env: {'DISPLAY': ':0',
20:10:09 INFO - 'HOME': '/home/cltbld',
20:10:09 INFO - 'LANG': 'en_US.UTF-8',
20:10:09 INFO - 'LANGUAGE': 'en_US:en',
20:10:09 INFO - 'LOGNAME': 'cltbld',
20:10:09 INFO - 'MAIL': '/var/mail/cltbld',
20:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:10:09 INFO - 'MOZ_NO_REMOTE': '1',
20:10:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:10:09 INFO - 'NO_EM_RESTART': '1',
20:10:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:10:09 INFO - 'PWD': '/builds/slave/test',
20:10:09 INFO - 'SHELL': '/bin/bash',
20:10:09 INFO - 'SHLVL': '1',
20:10:09 INFO - 'TERM': 'linux',
20:10:09 INFO - 'TMOUT': '86400',
20:10:09 INFO - 'USER': 'cltbld',
20:10:09 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:10:09 INFO - '_': '/tools/buildbot/bin/python'}
20:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple
20:10:09 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))
20:10:09 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))
20:10:09 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))
20:10:09 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))
20:10:09 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))
20:10:09 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))
20:10:09 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))
20:10:09 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))
20:10:09 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))
20:10:09 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
20:10:10 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
20:10:11 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
20:10:11 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))
20:10:11 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))
20:10:11 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))
20:10:11 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))
20:10:12 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
20:10:12 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
20:10:13 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
20:10:13 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
20:10:14 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
20:10:14 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
20:10:15 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
20:10:15 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
20:10:16 INFO - Found existing installation: requests 1.2.3
20:10:16 INFO - Uninstalling requests-1.2.3:
20:10:16 INFO - Successfully uninstalled requests-1.2.3
20:10:16 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
20:10:16 INFO - Return code: 0
20:10:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
20:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
20:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
20:10:16 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 0x29352d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b0da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2cb0930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, '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': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195', '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
20:10:16 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
20:10:16 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
20:10:16 INFO - Using env: {'DISPLAY': ':0',
20:10:16 INFO - 'HOME': '/home/cltbld',
20:10:16 INFO - 'LANG': 'en_US.UTF-8',
20:10:16 INFO - 'LANGUAGE': 'en_US:en',
20:10:16 INFO - 'LOGNAME': 'cltbld',
20:10:16 INFO - 'MAIL': '/var/mail/cltbld',
20:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:10:16 INFO - 'MOZ_NO_REMOTE': '1',
20:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:10:16 INFO - 'NO_EM_RESTART': '1',
20:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:10:16 INFO - 'PWD': '/builds/slave/test',
20:10:16 INFO - 'SHELL': '/bin/bash',
20:10:16 INFO - 'SHLVL': '1',
20:10:16 INFO - 'TERM': 'linux',
20:10:16 INFO - 'TMOUT': '86400',
20:10:16 INFO - 'USER': 'cltbld',
20:10:16 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:10:16 INFO - '_': '/tools/buildbot/bin/python'}
20:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple
20:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
20:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
20:10:16 INFO - Return code: 0
20:10:16 INFO - Running post-action listener: _resource_record_post_action
20:10:16 INFO - Running post-action listener: _start_resource_monitoring
20:10:16 INFO - Starting resource monitoring.
20:10:16 INFO - [mozharness: 2018-01-11 04:10:16.900980Z] Finished create-virtualenv step (success)
20:10:16 INFO - [mozharness: 2018-01-11 04:10:16.901495Z] Running install step.
20:10:16 INFO - Running pre-action listener: _resource_record_pre_action
20:10:16 INFO - Running main action method: install
20:10:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
20:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
20:10:17 INFO - Reading from file tmpfile_stdout
20:10:17 INFO - Reading from file tmpfile_stderr
20:10:17 INFO - Detecting whether we're running mozinstall >=1.0...
20:10:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
20:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
20:10:17 INFO - Reading from file tmpfile_stdout
20:10:17 INFO - Output received:
20:10:17 INFO - Usage: mozinstall [options] installer
20:10:17 INFO - Options:
20:10:17 INFO - -h, --help show this help message and exit
20:10:17 INFO - -d DEST, --destination=DEST
20:10:17 INFO - Directory to install application into. [default:
20:10:17 INFO - "/builds/slave/test"]
20:10:17 INFO - --app=APP Application being installed. [default: firefox]
20:10:17 INFO - mkdir: /builds/slave/test/build/application
20:10:17 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']
20:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
20:10:32 INFO - Reading from file tmpfile_stdout
20:10:32 INFO - Output received:
20:10:32 INFO - /builds/slave/test/build/application/firefox/firefox
20:10:32 INFO - Running post-action listener: _resource_record_post_action
20:10:32 INFO - [mozharness: 2018-01-11 04:10:32.777111Z] Finished install step (success)
20:10:32 INFO - [mozharness: 2018-01-11 04:10:32.777358Z] Running setup-mitmproxy step.
20:10:32 INFO - Running pre-action listener: _resource_record_pre_action
20:10:32 INFO - Running main action method: setup_mitmproxy
20:10:32 INFO - Skipping: mitmproxy is not required
20:10:32 INFO - Running post-action listener: _resource_record_post_action
20:10:32 INFO - [mozharness: 2018-01-11 04:10:32.778462Z] Finished setup-mitmproxy step (success)
20:10:32 INFO - [mozharness: 2018-01-11 04:10:32.778678Z] Running run-tests step.
20:10:32 INFO - Running pre-action listener: _resource_record_pre_action
20:10:32 INFO - Running pre-action listener: _set_gcov_prefix
20:10:32 INFO - Running main action method: run_tests
20:10:32 WARNING - Try message not found.
20:10:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
20:10:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
20:10:32 INFO - Python 2.7.3
20:10:32 INFO - Return code: 0
20:10:32 INFO - grabbing minidump binary from tooltool
20:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
20:10:32 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 0x2cadad0>, '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 0x2cae9d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2caee60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
20:10:32 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
20:10:32 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
20:10:32 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
20:10:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
20:10:32 INFO - Return code: 0
20:10:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
20:10:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
20:10:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
20:10:32 INFO - ENV: RUST_BACKTRACE is now full
20:10:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
20:10:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
20:10:32 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/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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
20:10:32 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/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --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
20:10:32 INFO - Using env: {'DISPLAY': ':0',
20:10:32 INFO - 'HOME': '/home/cltbld',
20:10:32 INFO - 'LANG': 'en_US.UTF-8',
20:10:32 INFO - 'LANGUAGE': 'en_US:en',
20:10:32 INFO - 'LOGNAME': 'cltbld',
20:10:32 INFO - 'MAIL': '/var/mail/cltbld',
20:10:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
20:10:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
20:10:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:10:32 INFO - 'MOZ_NO_REMOTE': '1',
20:10:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
20:10:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:10:32 INFO - 'NO_EM_RESTART': '1',
20:10:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:10:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:10:32 INFO - 'PWD': '/builds/slave/test',
20:10:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
20:10:32 INFO - 'RUST_BACKTRACE': 'full',
20:10:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
20:10:32 INFO - 'SHELL': '/bin/bash',
20:10:32 INFO - 'SHLVL': '1',
20:10:32 INFO - 'TERM': 'linux',
20:10:32 INFO - 'TMOUT': '86400',
20:10:32 INFO - 'USER': 'cltbld',
20:10:32 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:10:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:10:32 INFO - '_': '/tools/buildbot/bin/python'}
20:10:32 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/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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
20:10:33 INFO - mozversion application_buildid: 20180111024748
20:10:33 INFO - mozversion application_changeset: 3e7bec52b1431c8dfd56c86ec5062514434fa788
20:10:33 INFO - mozversion application_display_name: Firefox
20:10:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
20:10:33 INFO - mozversion application_name: Firefox
20:10:33 INFO - mozversion application_remotingname: firefox
20:10:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
20:10:33 INFO - mozversion application_vendor: Mozilla
20:10:33 INFO - mozversion application_version: 58.0
20:10:33 INFO - mozversion platform_buildid: 20180111024748
20:10:33 INFO - mozversion platform_changeset: 3e7bec52b1431c8dfd56c86ec5062514434fa788
20:10:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
20:10:33 INFO - mozversion platform_version: 58.0
20:10:33 INFO - using testdate: 1515643833
20:10:33 INFO - actual date: 1515643833
20:10:33 INFO - starting webserver on 'localhost:55549'
20:10:33 INFO - SUITE-START | Running 3 tests
20:10:33 INFO - TEST-START | basic_compositor_video
20:10:33 INFO - Initialising browser for basic_compositor_video test...
20:10:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55549/getInfo.html -profile /tmp/tmpxJvolL/profile
20:10:33 INFO - TEST-INFO | started process 12621 (/builds/slave/test/build/application/firefox/firefox http://localhost:55549/getInfo.html)
20:10:38 INFO - TEST-INFO | 12621: exit 0
20:10:38 INFO - Browser initialized.
20:10:38 INFO - Running cycle 1/1 for basic_compositor_video test...
20:10:38 INFO - Using env: {'DISPLAY': ':0',
20:10:38 INFO - 'HOME': '/home/cltbld',
20:10:38 INFO - 'JSGC_DISABLE_POISONING': '1',
20:10:38 INFO - 'LANG': 'en_US.UTF-8',
20:10:38 INFO - 'LANGUAGE': 'en_US:en',
20:10:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
20:10:38 INFO - 'LOGNAME': 'cltbld',
20:10:38 INFO - 'MAIL': '/var/mail/cltbld',
20:10:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
20:10:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
20:10:38 INFO - 'MOZ_CRASHREPORTER': '1',
20:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:10:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
20:10:38 INFO - 'MOZ_NO_REMOTE': '1',
20:10:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
20:10:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:10:38 INFO - 'NO_EM_RESTART': '1',
20:10:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:10:38 INFO - 'PWD': '/builds/slave/test',
20:10:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
20:10:38 INFO - 'RUST_BACKTRACE': 'full',
20:10:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
20:10:38 INFO - 'SHELL': '/bin/bash',
20:10:38 INFO - 'SHLVL': '1',
20:10:38 INFO - 'TERM': 'linux',
20:10:38 INFO - 'TMOUT': '86400',
20:10:38 INFO - 'USER': 'cltbld',
20:10:38 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:10:38 INFO - '_': '/tools/buildbot/bin/python'}
20:10:38 INFO - TEST-INFO | started process 12863 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxJvolL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
20:10:39 INFO - PID 12863 |
20:10:39 INFO - PID 12863 | (/builds/slave/test/build/application/firefox/firefox:12912): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
20:10:39 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6808515406162463 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.672892976588629 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7134665905197028 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7349522983521262 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.038896366083447 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.141449275362318 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.5856152927120646 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.522183098591553 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.227456445993034 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634929577464793 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.205102040816325 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 9.572727272727276 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.074169741697412 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.587619047619073 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.299262295081954 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.696725146198869 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45061068702292 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.241011235955023 ms/frame
20:11:25 INFO - PID 12863 |
20:11:25 INFO - PID 12863 | Cycle 1(1): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:12:11 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.688868880135059 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6737238493723852 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.77728672985782 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7563476733977168 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.087302452316076 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9456804733727795 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.4607843137254903 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4020408163265268 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.71474285714286 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603529411764703 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.311546391752577 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 9.903168316831694 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.032352941176455 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.5835978835979 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.246122448979573 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.838698224852102 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586795366795352 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052596685082895 ms/frame
20:12:11 INFO - PID 12863 |
20:12:11 INFO - PID 12863 | Cycle 1(2): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:12:57 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.692284263959391 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669582637729549 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7744056771141339 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7377758470894868 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.082530612244898 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.168416666666667 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.406129398410896 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3454849498327794 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672816635160677 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.604929971988793 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.455261324041812 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 9.711941747572816 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.280300751879704 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.585185185185178 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.502833333333335 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.769058823529443 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.237977528089864 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.81275675675676 ms/frame
20:12:57 INFO - PID 12863 |
20:12:57 INFO - PID 12863 | Cycle 1(3): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:13:44 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6848062886019093 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698330550918195 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.780474777448071 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7684880636604774 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093342428376535 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0824897959183675 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.473425925925929 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4425129087779665 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682310606060603 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5580000000000025 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.135743243243232 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 9.806176470588248 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.989230769230774 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.472146596858641 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296803278688518 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766941176470572 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.237528089887634 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.755268817204318 ms/frame
20:13:44 INFO - PID 12863 |
20:13:44 INFO - PID 12863 | Cycle 1(4): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:14:30 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6902647887323945 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6711445279866333 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7829827688651219 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7469170305676858 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.099672131147543 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.041737373737374 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.476639629200466 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.401972789115641 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7034220532319395 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.4222222222222305 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.7941726618705 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 10.207653061224493 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.157323420074349 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.758064516129032 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346090534979417 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.837988165680446 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.538999999999998 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.993186813186824 ms/frame
20:14:30 INFO - PID 12863 |
20:14:30 INFO - PID 12863 | Cycle 1(5): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:15:16 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681939461883408 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.67008347245409 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.726133486766398 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7278411053540588 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.172767732962447 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9232549019607856 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.50532710280374 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.454645941278062 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.68382575757576 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.363270777479893 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.274863013698637 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 10.05145728643215 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.070774907749092 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.530105263157902 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503166666666676 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.976886227544906 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.453053435114493 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990329670329698 ms/frame
20:15:16 INFO - PID 12863 |
20:15:16 INFO - PID 12863 | Cycle 1(6): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:16:02 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810196078431374 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689741451209341 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.774441159077469 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7441673931996515 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.13848275862069 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.017550200803212 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.4455109070034453 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.339165275459098 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704638783269961 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572479108635099 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.001200000000003 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 9.955422885572144 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.157026022304825 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.699786096256688 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.82384615384615 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97856287425153 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.496091954023 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.994615384615367 ms/frame
20:16:02 INFO - PID 12863 |
20:16:02 INFO - PID 12863 | Cycle 1(7): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:16:48 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680313549832027 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6697829716193657 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7670082449941102 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7322251082251086 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033682795698926 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.16758333333333 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.5128571428571425 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.424828767123285 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715199999999999 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.65056497175141 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.136756756756755 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 9.808627450980389 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.07062730627306 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.641595744680865 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606722689075637 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.049759036144591 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.364090909090919 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.053812154696121 ms/frame
20:16:48 INFO - PID 12863 |
20:16:48 INFO - PID 12863 | Cycle 1(8): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:17:34 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6883624085537423 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6667666666666665 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.724206896551724 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.711394354148845 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.172990264255913 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9222352941176473 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.5302823529411764 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4378350515463914 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.608411214953271 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587206703910607 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 9.937483443708606 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 10.053467336683415 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.070848708487102 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639999999999999 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347407407407431 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.630116279069751 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.239325842696635 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.813189189189202 ms/frame
20:17:34 INFO - PID 12863 |
20:17:34 INFO - PID 12863 | Cycle 1(9): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:18:20 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6790374930050365 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.671311612364244 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7271042026482444 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7052173913043474 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0997267759562845 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.083102040816325 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.448896551724137 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.350418760469013 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619850187265918 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603585434173668 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.310515463917527 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 9.307069767441863 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.071291512915128 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.530736842105282 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398264462809907 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.701871345029264 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.497164750957834 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.994175824175837 ms/frame
20:18:20 INFO - PID 12863 |
20:18:20 INFO - PID 12863 | Cycle 1(10): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:19:07 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6904338028169017 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685571309424516 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7377301679212505 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7350910667823074 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.037900403768508 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.142525879917181 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.4727314814814823 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4365635738831584 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.760076775431862 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.61859550561798 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.312783505154641 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 10.586772486772494 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.279022556390983 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.70106951871657 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.65864978902953 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907499999999995 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451450381679384 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.114666666666663 ms/frame
20:19:07 INFO - PID 12863 |
20:19:07 INFO - PID 12863 | Cycle 1(11): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:19:53 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6802351623740202 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.670016694490818 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7415322112594316 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6995072217502114 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_startup = 4.104240766073873 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8698646034816235 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_startup = 3.4647575057736715 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3791216216216258 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682234848484851 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589385474860335 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_startup = 10.454076655052264 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.480p.60fps.webm_scale_2_inclip = 10.422083333333338 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.989963369963377 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.58402116402115 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.712881355932195 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.979760479041888 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.238127340823949 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.049723756906078 ms/frame
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | Cycle 1(12): loaded http://localhost:55549/tests/video/video_playback.html (next: http://localhost:55549/tests/video/video_playback.html)
20:19:53 INFO - PID 12863 | __start_tp_report
20:19:53 INFO - PID 12863 | _x_x_mozilla_page_load
20:19:53 INFO - PID 12863 | _x_x_mozilla_page_load_details
20:19:53 INFO - PID 12863 | |i|pagename|runs|
20:19:53 INFO - PID 12863 | |0;240p.120fps.mp4_scale_1_startup;1.6808515406162463;1.688868880135059;1.692284263959391;1.6848062886019093;1.6902647887323945;1.681939461883408;1.6810196078431374;1.680313549832027;1.6883624085537423;1.6790374930050365;1.6904338028169017;1.6802351623740202
20:19:53 INFO - PID 12863 | |1;240p.120fps.mp4_scale_1_inclip;1.672892976588629;1.6737238493723852;1.669582637729549;1.6698330550918195;1.6711445279866333;1.67008347245409;1.6689741451209341;1.6697829716193657;1.6667666666666665;1.671311612364244;1.6685571309424516;1.670016694490818
20:19:53 INFO - PID 12863 | |2;240p.120fps.mp4_scale_1.1_startup;1.7134665905197028;1.77728672985782;1.7744056771141339;1.780474777448071;1.7829827688651219;1.726133486766398;1.774441159077469;1.7670082449941102;1.724206896551724;1.7271042026482444;1.7377301679212505;1.7415322112594316
20:19:53 INFO - PID 12863 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7349522983521262;1.7563476733977168;1.7377758470894868;1.7684880636604774;1.7469170305676858;1.7278411053540588;1.7441673931996515;1.7322251082251086;1.711394354148845;1.7052173913043474;1.7350910667823074;1.6995072217502114
20:19:53 INFO - PID 12863 | |4;240p.120fps.mp4_scale_2_startup;4.038896366083447;4.087302452316076;4.082530612244898;4.093342428376535;4.099672131147543;4.172767732962447;4.13848275862069;4.033682795698926;4.172990264255913;4.0997267759562845;4.037900403768508;4.104240766073873
20:19:53 INFO - PID 12863 | |5;240p.120fps.mp4_scale_2_inclip;4.141449275362318;3.9456804733727795;4.168416666666667;4.0824897959183675;4.041737373737374;3.9232549019607856;4.017550200803212;4.16758333333333;3.9222352941176473;4.083102040816325;4.142525879917181;3.8698646034816235
20:19:53 INFO - PID 12863 | |6;480p.60fps.webm_scale_1_startup;3.5856152927120646;3.4607843137254903;3.406129398410896;3.473425925925929;3.476639629200466;3.50532710280374;3.4455109070034453;3.5128571428571425;3.5302823529411764;3.448896551724137;3.4727314814814823;3.4647575057736715
20:19:53 INFO - PID 12863 | |7;480p.60fps.webm_scale_1_inclip;3.522183098591553;3.4020408163265268;3.3454849498327794;3.4425129087779665;3.401972789115641;3.454645941278062;3.339165275459098;3.424828767123285;3.4378350515463914;3.350418760469013;3.4365635738831584;3.3791216216216258
20:19:53 INFO - PID 12863 | |8;480p.60fps.webm_scale_1.1_startup;5.227456445993034;5.71474285714286;5.672816635160677;5.682310606060603;5.7034220532319395;5.68382575757576;5.704638783269961;5.715199999999999;5.608411214953271;5.619850187265918;5.760076775431862;5.682234848484851
20:19:53 INFO - PID 12863 | |9;480p.60fps.webm_scale_1.1_inclip;5.634929577464793;5.603529411764703;5.604929971988793;5.5580000000000025;5.4222222222222305;5.363270777479893;5.572479108635099;5.65056497175141;5.587206703910607;5.603585434173668;5.61859550561798;5.589385474860335
20:19:53 INFO - PID 12863 | |10;480p.60fps.webm_scale_2_startup;10.205102040816325;10.311546391752577;10.455261324041812;10.135743243243232;10.7941726618705;10.274863013698637;10.001200000000003;10.136756756756755;9.937483443708606;10.310515463917527;10.312783505154641;10.454076655052264
20:19:53 INFO - PID 12863 | |11;480p.60fps.webm_scale_2_inclip;9.572727272727276;9.903168316831694;9.711941747572816;9.806176470588248;10.207653061224493;10.05145728643215;9.955422885572144;9.808627450980389;10.053467336683415;9.307069767441863;10.586772486772494;10.422083333333338
20:19:53 INFO - PID 12863 | |12;1080p.60fps.mp4_scale_1_startup;11.074169741697412;11.032352941176455;11.280300751879704;10.989230769230774;11.157323420074349;11.070774907749092;11.157026022304825;11.07062730627306;11.070848708487102;11.071291512915128;11.279022556390983;10.989963369963377
20:19:53 INFO - PID 12863 | |13;1080p.60fps.mp4_scale_1_inclip;10.587619047619073;10.5835978835979;10.585185185185178;10.472146596858641;10.758064516129032;10.530105263157902;10.699786096256688;10.641595744680865;10.639999999999999;10.530736842105282;10.70106951871657;10.58402116402115
20:19:53 INFO - PID 12863 | |14;1080p.60fps.mp4_scale_1.1_startup;12.299262295081954;12.246122448979573;12.502833333333335;12.296803278688518;12.346090534979417;12.503166666666676;12.82384615384615;12.606722689075637;12.347407407407431;12.398264462809907;12.65864978902953;12.712881355932195
20:19:53 INFO - PID 12863 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.696725146198869;11.838698224852102;11.769058823529443;11.766941176470572;11.837988165680446;11.976886227544906;11.97856287425153;12.049759036144591;11.630116279069751;11.701871345029264;11.907499999999995;11.979760479041888
20:19:53 INFO - PID 12863 | |16;1080p.60fps.mp4_scale_2_startup;11.45061068702292;11.586795366795352;11.237977528089864;11.237528089887634;11.538999999999998;11.453053435114493;11.496091954023;11.364090909090919;11.239325842696635;11.497164750957834;11.451450381679384;11.238127340823949
20:19:53 INFO - PID 12863 | |17;1080p.60fps.mp4_scale_2_inclip;11.241011235955023;11.052596685082895;10.81275675675676;10.755268817204318;10.993186813186824;10.990329670329698;10.994615384615367;11.053812154696121;10.813189189189202;10.994175824175837;11.114666666666663;11.049723756906078
20:19:53 INFO - PID 12863 | __end_tp_report
20:19:53 INFO - PID 12863 | __start_cc_report
20:19:53 INFO - PID 12863 | _x_x_mozilla_cycle_collect,565
20:19:53 INFO - PID 12863 | __end_cc_report
20:19:53 INFO - PID 12863 | __startTimestamp1515644393490__endTimestamp
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | ------- Summary: start -------
20:19:53 INFO - PID 12863 | Number of tests: 18
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.69 stddev:0.00 (0.3%) stddev-sans-first:0.00
20:19:53 INFO - PID 12863 | 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
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#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
20:19:53 INFO - PID 12863 | 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
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.75 Median:1.77 stddev:0.03 (1.5%) stddev-sans-first:0.02
20:19:53 INFO - PID 12863 | Values: 1.7 1.8 1.8 1.8 1.8 1.7 1.8 1.8 1.7 1.7 1.7 1.7
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.73 Median:1.74 stddev:0.02 (1.2%) stddev-sans-first:0.02
20:19:53 INFO - PID 12863 | Values: 1.7 1.8 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.10 Median:4.10 stddev:0.05 (1.2%) stddev-sans-first:0.05
20:19:53 INFO - PID 12863 | Values: 4.0 4.1 4.1 4.1 4.1 4.2 4.1 4.0 4.2 4.1 4.0 4.1
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.04 Median:4.08 stddev:0.11 (2.6%) stddev-sans-first:0.11
20:19:53 INFO - PID 12863 | Values: 4.1 3.9 4.2 4.1 4.0 3.9 4.0 4.2 3.9 4.1 4.1 3.9
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.48 Median:3.48 stddev:0.05 (1.3%) stddev-sans-first:0.03
20:19:53 INFO - PID 12863 | Values: 3.6 3.5 3.4 3.5 3.5 3.5 3.4 3.5 3.5 3.4 3.5 3.5
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.41 Median:3.43 stddev:0.05 (1.6%) stddev-sans-first:0.04
20:19:53 INFO - PID 12863 | Values: 3.5 3.4 3.3 3.4 3.4 3.5 3.3 3.4 3.4 3.4 3.4 3.4
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.65 Median:5.69 stddev:0.14 (2.4%) stddev-sans-first:0.04
20:19:53 INFO - PID 12863 | Values: 5.2 5.7 5.7 5.7 5.7 5.7 5.7 5.7 5.6 5.6 5.8 5.7
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.57 Median:5.60 stddev:0.09 (1.5%) stddev-sans-first:0.09
20:19:53 INFO - PID 12863 | Values: 5.6 5.6 5.6 5.6 5.4 5.4 5.6 5.7 5.6 5.6 5.6 5.6
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.28 Median:10.31 stddev:0.23 (2.2%) stddev-sans-first:0.24
20:19:53 INFO - PID 12863 | Values: 10.2 10.3 10.5 10.1 10.8 10.3 10.0 10.1 9.9 10.3 10.3 10.5
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.95 Median:9.64 stddev:0.35 (3.7%) stddev-sans-first:0.35
20:19:53 INFO - PID 12863 | Values: 9.6 9.9 9.7 9.8 10.2 10.1 10.0 9.8 10.1 9.3 10.6 10.4
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.10 Median:11.07 stddev:0.10 (0.9%) stddev-sans-first:0.10
20:19:53 INFO - PID 12863 | Values: 11.1 11.0 11.3 11.0 11.2 11.1 11.2 11.1 11.1 11.1 11.3 11.0
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.61 Median:10.61 stddev:0.08 (0.8%) stddev-sans-first:0.09
20:19:53 INFO - PID 12863 | Values: 10.6 10.6 10.6 10.5 10.8 10.5 10.7 10.6 10.6 10.5 10.7 10.6
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.48 Median:12.50 stddev:0.19 (1.5%) stddev-sans-first:0.19
20:19:53 INFO - PID 12863 | Values: 12.3 12.2 12.5 12.3 12.3 12.5 12.8 12.6 12.3 12.4 12.7 12.7
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.84 Median:11.87 stddev:0.13 (1.1%) stddev-sans-first:0.13
20:19:53 INFO - PID 12863 | Values: 11.7 11.8 11.8 11.8 11.8 12.0 12.0 12.0 11.6 11.7 11.9 12.0
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.40 Median:11.45 stddev:0.13 (1.1%) stddev-sans-first:0.14
20:19:53 INFO - PID 12863 | Values: 11.5 11.6 11.2 11.2 11.5 11.5 11.5 11.4 11.2 11.5 11.5 11.2
20:19:53 INFO - PID 12863 |
20:19:53 INFO - PID 12863 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.99 Median:11.02 stddev:0.14 (1.2%) stddev-sans-first:0.12
20:19:53 INFO - PID 12863 | Values: 11.2 11.1 10.8 10.8 11.0 11.0 11.0 11.1 10.8 11.0 11.1 11.0
20:19:53 INFO - PID 12863 | -------- Summary: end --------
20:19:53 INFO - PID 12863 |
20:19:54 INFO - TEST-INFO | 12863: exit 0
20:19:54 INFO - TEST-OK | basic_compositor_video | took 560862ms
20:19:54 INFO - TEST-START | glvideo
20:19:54 INFO - Initialising browser for glvideo test...
20:19:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55549/getInfo.html -profile /tmp/tmp9zmkLE/profile
20:19:54 INFO - TEST-INFO | started process 14085 (/builds/slave/test/build/application/firefox/firefox http://localhost:55549/getInfo.html)
20:19:59 INFO - TEST-INFO | 14085: exit 0
20:19:59 INFO - Browser initialized.
20:19:59 INFO - Running cycle 1/1 for glvideo test...
20:19:59 INFO - Using env: {'DISPLAY': ':0',
20:19:59 INFO - 'HOME': '/home/cltbld',
20:19:59 INFO - 'JSGC_DISABLE_POISONING': '1',
20:19:59 INFO - 'LANG': 'en_US.UTF-8',
20:19:59 INFO - 'LANGUAGE': 'en_US:en',
20:19:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
20:19:59 INFO - 'LOGNAME': 'cltbld',
20:19:59 INFO - 'MAIL': '/var/mail/cltbld',
20:19:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
20:19:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
20:19:59 INFO - 'MOZ_CRASHREPORTER': '1',
20:19:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:19:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
20:19:59 INFO - 'MOZ_NO_REMOTE': '1',
20:19:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
20:19:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:19:59 INFO - 'NO_EM_RESTART': '1',
20:19:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:19:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:19:59 INFO - 'PWD': '/builds/slave/test',
20:19:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
20:19:59 INFO - 'RUST_BACKTRACE': 'full',
20:19:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
20:19:59 INFO - 'SHELL': '/bin/bash',
20:19:59 INFO - 'SHLVL': '1',
20:19:59 INFO - 'TERM': 'linux',
20:19:59 INFO - 'TMOUT': '86400',
20:19:59 INFO - 'USER': 'cltbld',
20:19:59 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:19:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:19:59 INFO - '_': '/tools/buildbot/bin/python'}
20:19:59 INFO - TEST-INFO | started process 14321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9zmkLE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
20:20:00 INFO - PID 14321 |
20:20:00 INFO - PID 14321 | (/builds/slave/test/build/application/firefox/firefox:14371): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
20:20:00 INFO - PID 14321 |
20:20:01 INFO - PID 14321 | [talos glvideo result] Mean tick time across 100 ticks: 7.721 ms
20:20:01 INFO - PID 14321 | Cycle 1(1): loaded http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html)
20:20:03 INFO - PID 14321 | [talos glvideo result] Mean tick time across 100 ticks: 6.628000000000001 ms
20:20:03 INFO - PID 14321 | Cycle 1(2): loaded http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html)
20:20:04 INFO - PID 14321 | [talos glvideo result] Mean tick time across 100 ticks: 6.568 ms
20:20:04 INFO - PID 14321 | Cycle 1(3): loaded http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html)
20:20:05 INFO - PID 14321 | [talos glvideo result] Mean tick time across 100 ticks: 6.5072 ms
20:20:05 INFO - PID 14321 | Cycle 1(4): loaded http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html)
20:20:07 INFO - PID 14321 | [talos glvideo result] Mean tick time across 100 ticks: 6.625799999999999 ms
20:20:07 INFO - PID 14321 | Cycle 1(5): loaded http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:55549/tests/webgl/benchmarks/video/video_upload.html)
20:20:07 INFO - PID 14321 | __start_tp_report
20:20:07 INFO - PID 14321 | _x_x_mozilla_page_load
20:20:07 INFO - PID 14321 | _x_x_mozilla_page_load_details
20:20:07 INFO - PID 14321 | |i|pagename|runs|
20:20:07 INFO - PID 14321 | |0;Mean tick time across 100 ticks: ;7.721;6.628000000000001;6.568;6.5072;6.625799999999999
20:20:07 INFO - PID 14321 | __end_tp_report
20:20:07 INFO - PID 14321 | __start_cc_report
20:20:07 INFO - PID 14321 | _x_x_mozilla_cycle_collect,260
20:20:07 INFO - PID 14321 | __end_cc_report
20:20:07 INFO - PID 14321 | __startTimestamp1515644407535__endTimestamp
20:20:07 INFO - PID 14321 |
20:20:07 INFO - PID 14321 | ------- Summary: start -------
20:20:07 INFO - PID 14321 | Number of tests: 1
20:20:07 INFO - PID 14321 |
20:20:07 INFO - PID 14321 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.81 Median:6.63 stddev:0.51 (7.7%) stddev-sans-first:0.06
20:20:07 INFO - PID 14321 | Values: 7.7 6.6 6.6 6.5 6.6
20:20:07 INFO - PID 14321 | -------- Summary: end --------
20:20:07 INFO - PID 14321 |
20:20:07 INFO - PID 14321 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
20:20:08 INFO - TEST-INFO | 14321: exit 0
20:20:08 INFO - TEST-OK | glvideo | took 14110ms
20:20:08 INFO - TEST-START | displaylist_mutate
20:20:08 INFO - Initialising browser for displaylist_mutate test...
20:20:08 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:55549/getInfo.html -profile /tmp/tmpEckOl9/profile
20:20:08 INFO - TEST-INFO | started process 14543 (/builds/slave/test/build/application/firefox/firefox http://localhost:55549/getInfo.html)
20:20:13 INFO - TEST-INFO | 14543: exit 0
20:20:13 INFO - Browser initialized.
20:20:13 INFO - Running cycle 1/1 for displaylist_mutate test...
20:20:13 INFO - Using env: {'DISPLAY': ':0',
20:20:13 INFO - 'HOME': '/home/cltbld',
20:20:13 INFO - 'JSGC_DISABLE_POISONING': '1',
20:20:13 INFO - 'LANG': 'en_US.UTF-8',
20:20:13 INFO - 'LANGUAGE': 'en_US:en',
20:20:13 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
20:20:13 INFO - 'LOGNAME': 'cltbld',
20:20:13 INFO - 'MAIL': '/var/mail/cltbld',
20:20:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
20:20:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
20:20:13 INFO - 'MOZ_CRASHREPORTER': '1',
20:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
20:20:13 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
20:20:13 INFO - 'MOZ_NO_REMOTE': '1',
20:20:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
20:20:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
20:20:13 INFO - 'NO_EM_RESTART': '1',
20:20:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
20:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
20:20:13 INFO - 'PWD': '/builds/slave/test',
20:20:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
20:20:13 INFO - 'RUST_BACKTRACE': 'full',
20:20:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
20:20:13 INFO - 'SHELL': '/bin/bash',
20:20:13 INFO - 'SHLVL': '1',
20:20:13 INFO - 'TERM': 'linux',
20:20:13 INFO - 'TMOUT': '86400',
20:20:13 INFO - 'USER': 'cltbld',
20:20:13 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195',
20:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
20:20:13 INFO - '_': '/tools/buildbot/bin/python'}
20:20:13 INFO - TEST-INFO | started process 14780 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEckOl9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
20:20:14 INFO - PID 14780 |
20:20:14 INFO - PID 14780 | (/builds/slave/test/build/application/firefox/firefox:14829): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
20:20:14 INFO - PID 14780 |
20:20:22 INFO - PID 14780 | Cycle 1(1): loaded http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html)
20:20:31 INFO - PID 14780 | Cycle 1(2): loaded http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html)
20:20:39 INFO - PID 14780 | Cycle 1(3): loaded http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html)
20:20:48 INFO - PID 14780 | Cycle 1(4): loaded http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html)
20:20:57 INFO - PID 14780 | Cycle 1(5): loaded http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:55549/tests/layout/benchmarks/displaylist_mutate.html)
20:20:57 INFO - PID 14780 | __start_tp_report
20:20:57 INFO - PID 14780 | _x_x_mozilla_page_load
20:20:57 INFO - PID 14780 | _x_x_mozilla_page_load_details
20:20:57 INFO - PID 14780 | |i|pagename|runs|
20:20:57 INFO - PID 14780 | |0;/displaylist_mutate.html;7728.54;7946.88;7983.76;8065.52;8019.9800000000005
20:20:57 INFO - PID 14780 | __end_tp_report
20:20:57 INFO - PID 14780 | __start_cc_report
20:20:57 INFO - PID 14780 | _x_x_mozilla_cycle_collect,194
20:20:57 INFO - PID 14780 | __end_cc_report
20:20:57 INFO - PID 14780 | __startTimestamp1515644457771__endTimestamp
20:20:57 INFO - PID 14780 |
20:20:57 INFO - PID 14780 | ------- Summary: start -------
20:20:57 INFO - PID 14780 | Number of tests: 1
20:20:57 INFO - PID 14780 |
20:20:57 INFO - PID 14780 | [#0] /displaylist_mutate.html Cycles:5 Average:7948.94 Median:7983.76 stddev:130.80 (1.6%) stddev-sans-first:50.70
20:20:57 INFO - PID 14780 | Values: 7728.5 7946.9 7983.8 8065.5 8020.0
20:20:57 INFO - PID 14780 | -------- Summary: end --------
20:20:57 INFO - PID 14780 |
20:20:58 INFO - PID 14780 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
20:20:58 INFO - TEST-INFO | 14780: exit 0
20:20:58 INFO - TEST-OK | displaylist_mutate | took 50267ms
20:20:58 INFO - SUITE-END | took 625s
20:20:58 INFO - Completed test suite (00:10:25)
20:20:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.585407093536521, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6808515406162463, 1.688868880135059, 1.692284263959391, 1.6848062886019093, 1.6902647887323945, 1.681939461883408, 1.6810196078431374, 1.680313549832027, 1.6883624085537423, 1.6790374930050365, 1.6904338028169017, 1.6802351623740202], "value": 1.6848062886019093, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.672892976588629, 1.6737238493723852, 1.669582637729549, 1.6698330550918195, 1.6711445279866333, 1.67008347245409, 1.6689741451209341, 1.6697829716193657, 1.6667666666666665, 1.671311612364244, 1.6685571309424516, 1.670016694490818], "value": 1.6698330550918195, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7134665905197028, 1.77728672985782, 1.7744056771141339, 1.780474777448071, 1.7829827688651219, 1.726133486766398, 1.774441159077469, 1.7670082449941102, 1.724206896551724, 1.7271042026482444, 1.7377301679212505, 1.7415322112594316], "value": 1.7670082449941102, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7349522983521262, 1.7563476733977168, 1.7377758470894868, 1.7684880636604774, 1.7469170305676858, 1.7278411053540588, 1.7441673931996515, 1.7322251082251086, 1.711394354148845, 1.7052173913043474, 1.7350910667823074, 1.6995072217502114], "value": 1.7350910667823074, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.038896366083447, 4.087302452316076, 4.082530612244898, 4.093342428376535, 4.099672131147543, 4.172767732962447, 4.13848275862069, 4.033682795698926, 4.172990264255913, 4.0997267759562845, 4.037900403768508, 4.104240766073873], "value": 4.099672131147543, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.141449275362318, 3.9456804733727795, 4.168416666666667, 4.0824897959183675, 4.041737373737374, 3.9232549019607856, 4.017550200803212, 4.16758333333333, 3.9222352941176473, 4.083102040816325, 4.142525879917181, 3.8698646034816235], "value": 4.041737373737374, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5856152927120646, 3.4607843137254903, 3.406129398410896, 3.473425925925929, 3.476639629200466, 3.50532710280374, 3.4455109070034453, 3.5128571428571425, 3.5302823529411764, 3.448896551724137, 3.4727314814814823, 3.4647575057736715], "value": 3.4727314814814823, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.522183098591553, 3.4020408163265268, 3.3454849498327794, 3.4425129087779665, 3.401972789115641, 3.454645941278062, 3.339165275459098, 3.424828767123285, 3.4378350515463914, 3.350418760469013, 3.4365635738831584, 3.3791216216216258], "value": 3.4020408163265268, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.227456445993034, 5.71474285714286, 5.672816635160677, 5.682310606060603, 5.7034220532319395, 5.68382575757576, 5.704638783269961, 5.715199999999999, 5.608411214953271, 5.619850187265918, 5.760076775431862, 5.682234848484851], "value": 5.68382575757576, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.634929577464793, 5.603529411764703, 5.604929971988793, 5.5580000000000025, 5.4222222222222305, 5.363270777479893, 5.572479108635099, 5.65056497175141, 5.587206703910607, 5.603585434173668, 5.61859550561798, 5.589385474860335], "value": 5.589385474860335, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.205102040816325, 10.311546391752577, 10.455261324041812, 10.135743243243232, 10.7941726618705, 10.274863013698637, 10.001200000000003, 10.136756756756755, 9.937483443708606, 10.310515463917527, 10.312783505154641, 10.454076655052264], "value": 10.310515463917527, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.572727272727276, 9.903168316831694, 9.711941747572816, 9.806176470588248, 10.207653061224493, 10.05145728643215, 9.955422885572144, 9.808627450980389, 10.053467336683415, 9.307069767441863, 10.586772486772494, 10.422083333333338], "value": 9.955422885572144, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.074169741697412, 11.032352941176455, 11.280300751879704, 10.989230769230774, 11.157323420074349, 11.070774907749092, 11.157026022304825, 11.07062730627306, 11.070848708487102, 11.071291512915128, 11.279022556390983, 10.989963369963377], "value": 11.070848708487102, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.587619047619073, 10.5835978835979, 10.585185185185178, 10.472146596858641, 10.758064516129032, 10.530105263157902, 10.699786096256688, 10.641595744680865, 10.639999999999999, 10.530736842105282, 10.70106951871657, 10.58402116402115], "value": 10.585185185185178, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.299262295081954, 12.246122448979573, 12.502833333333335, 12.296803278688518, 12.346090534979417, 12.503166666666676, 12.82384615384615, 12.606722689075637, 12.347407407407431, 12.398264462809907, 12.65864978902953, 12.712881355932195], "value": 12.502833333333335, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.696725146198869, 11.838698224852102, 11.769058823529443, 11.766941176470572, 11.837988165680446, 11.976886227544906, 11.97856287425153, 12.049759036144591, 11.630116279069751, 11.701871345029264, 11.907499999999995, 11.979760479041888], "value": 11.838698224852102, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.45061068702292, 11.586795366795352, 11.237977528089864, 11.237528089887634, 11.538999999999998, 11.453053435114493, 11.496091954023, 11.364090909090919, 11.239325842696635, 11.497164750957834, 11.451450381679384, 11.238127340823949], "value": 11.451450381679384, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.241011235955023, 11.052596685082895, 10.81275675675676, 10.755268817204318, 10.993186813186824, 10.990329670329698, 10.994615384615367, 11.053812154696121, 10.813189189189202, 10.994175824175837, 11.114666666666663, 11.049723756906078], "value": 10.994175824175837, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.721, 6.628000000000001, 6.568, 6.5072, 6.625799999999999], "value": 6.5969, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7728.54, 7946.88, 7983.76, 8065.52, 8019.9800000000005], "value": 8001.870000000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
20:20:58 INFO - Return code: 0
20:20:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
20:20:58 INFO - # TBPL SUCCESS #
20:20:58 INFO - Running post-action listener: _package_coverage_data
20:20:58 INFO - Running post-action listener: _resource_record_post_action
20:20:58 INFO - [mozharness: 2018-01-11 04:20:58.976558Z] Finished run-tests step (success)
20:20:58 INFO - Running post-run listener: _resource_record_post_run
20:20:59 INFO - Total resource usage - Wall time: 642s; CPU: 37.0%; Read bytes: 8892416; Write bytes: 307871744; Read time: 648; Write time: 146524
20:20:59 INFO - TinderboxPrint: CPU usage
36.6%
20:20:59 INFO - TinderboxPrint: I/O read bytes / time
8,892,416 / 648
20:20:59 INFO - TinderboxPrint: I/O write bytes / time
307,871,744 / 146,524
20:20:59 INFO - TinderboxPrint: CPU idle
3,196.1 (63.4%)
20:20:59 INFO - TinderboxPrint: CPU system
314.9 (6.2%)
20:20:59 INFO - TinderboxPrint: CPU user
1,512.6 (30.0%)
20:20:59 INFO - TinderboxPrint: Swap in / out
0 / 0
20:20:59 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 5332992; Read time: 0; Write time: 6104
20:20:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
20:20:59 INFO - run-tests - Wall time: 626s; CPU: 37.0%; Read bytes: 8892416; Write bytes: 302538752; Read time: 648; Write time: 140420
20:20:59 INFO - Running post-run listener: _upload_blobber_files
20:20:59 INFO - Blob upload gear active.
20:20:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
20:20:59 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
20:20:59 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']
20:20:59 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
20:20:59 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
20:20:59 INFO - SNIMissingWarning
20:20:59 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
20:20:59 INFO - InsecurePlatformWarning
20:20:59 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.)
20:20:59 INFO - SubjectAltNameWarning
20:20:59 INFO - (blobuploader) - INFO - Open directory for files ...
20:20:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
20:20:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:20:59 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:20:59 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
20:20:59 INFO - InsecurePlatformWarning
20:20:59 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.)
20:20:59 INFO - SubjectAltNameWarning
20:21:02 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
20:21:02 INFO - InsecurePlatformWarning
20:21:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
20:21:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:21:02 INFO - (blobuploader) - INFO - Done attempting.
20:21:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
20:21:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:21:02 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:21:02 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
20:21:02 INFO - InsecurePlatformWarning
20:21:02 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.)
20:21:02 INFO - SubjectAltNameWarning
20:21:03 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
20:21:03 INFO - InsecurePlatformWarning
20:21:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
20:21:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:21:03 INFO - (blobuploader) - INFO - Done attempting.
20:21:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
20:21:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:21:03 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:21:03 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
20:21:03 INFO - InsecurePlatformWarning
20:21:03 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.)
20:21:03 INFO - SubjectAltNameWarning
20:21:04 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
20:21:04 INFO - InsecurePlatformWarning
20:21:05 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
20:21:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:21:05 INFO - (blobuploader) - INFO - Done attempting.
20:21:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
20:21:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
20:21:05 INFO - (blobuploader) - INFO - Uploading, attempt #1.
20:21:05 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
20:21:05 INFO - InsecurePlatformWarning
20:21:05 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.)
20:21:05 INFO - SubjectAltNameWarning
20:21:05 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
20:21:05 INFO - InsecurePlatformWarning
20:21:06 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
20:21:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
20:21:06 INFO - (blobuploader) - INFO - Done attempting.
20:21:06 INFO - (blobuploader) - INFO - Iteration through files over.
20:21:06 INFO - Return code: 0
20:21:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
20:21:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
20:21:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/11cd5f9edb9bfdc288fd8d15e7554bef50c04513f659babc26fda1b3b3a7134945cd112e2438c9dfd93ae78f473d849d417f0bf3799eb58dc7d8f76dd31279a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2ea64c4ba3594e643795cf4204500804f4769cd4742131be8e2c1c6d9e02754ba5fdb74735abea3f5faa8ed7b1fc198f87955a09b8dcb4f7a4f8861e8a7bf497", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a1fc56e355c52cdb1ed74ce2afcc046ca5201ac33a66bf9701179666a0796c04fc192b7219f0207ae0e7db605a5cccab04c8ba46f4bb230960bcd7ad0a3146f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6ce9cc9af0a976e8d2e75485c47c6a19e85888a7d1eb4b2a34baf698676a5b72e1d281e9be88ebd84463f9674ffc79eccfd68d5fa4e027083373ecdc57d5aa81"}
20:21:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
20:21:06 INFO - Writing to file /builds/slave/test/properties/blobber_files
20:21:06 INFO - Contents:
20:21:06 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/11cd5f9edb9bfdc288fd8d15e7554bef50c04513f659babc26fda1b3b3a7134945cd112e2438c9dfd93ae78f473d849d417f0bf3799eb58dc7d8f76dd31279a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2ea64c4ba3594e643795cf4204500804f4769cd4742131be8e2c1c6d9e02754ba5fdb74735abea3f5faa8ed7b1fc198f87955a09b8dcb4f7a4f8861e8a7bf497", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a1fc56e355c52cdb1ed74ce2afcc046ca5201ac33a66bf9701179666a0796c04fc192b7219f0207ae0e7db605a5cccab04c8ba46f4bb230960bcd7ad0a3146f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6ce9cc9af0a976e8d2e75485c47c6a19e85888a7d1eb4b2a34baf698676a5b72e1d281e9be88ebd84463f9674ffc79eccfd68d5fa4e027083373ecdc57d5aa81"}
20:21:06 INFO - Running post-run listener: copy_logs_to_upload_dir
20:21:06 INFO - Copying logs to upload dir...
20:21:06 INFO - mkdir: /builds/slave/test/build/upload/logs
20:21:06 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=703.977201
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 44 secs) (at 2018-01-10 20:21:06.172421) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2018-01-10 20:21:06.176221) =========
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=9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/11cd5f9edb9bfdc288fd8d15e7554bef50c04513f659babc26fda1b3b3a7134945cd112e2438c9dfd93ae78f473d849d417f0bf3799eb58dc7d8f76dd31279a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2ea64c4ba3594e643795cf4204500804f4769cd4742131be8e2c1c6d9e02754ba5fdb74735abea3f5faa8ed7b1fc198f87955a09b8dcb4f7a4f8861e8a7bf497", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a1fc56e355c52cdb1ed74ce2afcc046ca5201ac33a66bf9701179666a0796c04fc192b7219f0207ae0e7db605a5cccab04c8ba46f4bb230960bcd7ad0a3146f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6ce9cc9af0a976e8d2e75485c47c6a19e85888a7d1eb4b2a34baf698676a5b72e1d281e9be88ebd84463f9674ffc79eccfd68d5fa4e027083373ecdc57d5aa81"}
build_url:https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011448
build_url: 'https://queue.taskcluster.net/v1/task/E6HoyLRJSa68Rdpa2Q89Aw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/11cd5f9edb9bfdc288fd8d15e7554bef50c04513f659babc26fda1b3b3a7134945cd112e2438c9dfd93ae78f473d849d417f0bf3799eb58dc7d8f76dd31279a6", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/2ea64c4ba3594e643795cf4204500804f4769cd4742131be8e2c1c6d9e02754ba5fdb74735abea3f5faa8ed7b1fc198f87955a09b8dcb4f7a4f8861e8a7bf497", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8a1fc56e355c52cdb1ed74ce2afcc046ca5201ac33a66bf9701179666a0796c04fc192b7219f0207ae0e7db605a5cccab04c8ba46f4bb230960bcd7ad0a3146f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/6ce9cc9af0a976e8d2e75485c47c6a19e85888a7d1eb4b2a34baf698676a5b72e1d281e9be88ebd84463f9674ffc79eccfd68d5fa4e027083373ecdc57d5aa81"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2018-01-10 20:21:06.206737) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-01-10 20:21:06.207070) =========
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=9f7544b6a75ada7b6b576032000001a7-1515643759.335531-1263326195
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004675
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2018-01-10 20:21:06.288900) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2018-01-10 20:21:06.289214) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2018-01-10 20:21:06.289604) =========
========= Total master_lag: 0.23 =========