builder: mozilla-central_ubuntu64_hw_test-g4-e10s-pgo
slave: talos-linux64-ix-066
starttime: 1510442926.12
results: success (0)
revision: 58b6b371c2f85614ab52dc2d6235e12af020dc1f
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.123477) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.123944) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.124218) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.170033) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.170444) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-11 15:28:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 227M=0s
2017-11-11 15:28:46 (227 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.219742
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.408493) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.408802) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.017101
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:46.458286) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-11 15:28:46.458651) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 58b6b371c2f85614ab52dc2d6235e12af020dc1f --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 58b6b371c2f85614ab52dc2d6235e12af020dc1f --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=82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-11 15:28:46,513 truncating revision to first 12 chars
2017-11-11 15:28:46,513 Setting DEBUG logging.
2017-11-11 15:28:46,513 attempt 1/10
2017-11-11 15:28:46,513 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/58b6b371c2f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-11 15:28:46,864 unpacking tar archive at: mozilla-central-58b6b371c2f8/testing/mozharness/
program finished with exit code 0
elapsedTime=1.028245
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-11 15:28:47.506162) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:47.506532) =========
script_repo_revision: 58b6b371c2f85614ab52dc2d6235e12af020dc1f
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:47.506952) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:47.507211) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-11 15:28:47.522708) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 48 secs) (at 2017-11-11 15:28:47.523091) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
15:28:47 INFO - MultiFileLogger online at 20171111 15:28:47 in /builds/slave/test
15:28:47 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
15:28:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
15:28:47 INFO - {'append_to_log': False,
15:28:47 INFO - 'base_work_dir': '/builds/slave/test',
15:28:47 INFO - 'blob_upload_branch': 'Firefox',
15:28:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
15:28:47 INFO - 'branch': 'Firefox',
15:28:47 INFO - 'buildbot_json_path': 'buildprops.json',
15:28:47 INFO - 'code_coverage': False,
15:28:47 INFO - 'config_files': ('talos/linux_config.py',),
15:28:47 INFO - 'default_actions': ('clobber',
15:28:47 INFO - 'read-buildbot-config',
15:28:47 INFO - 'download-and-extract',
15:28:47 INFO - 'populate-webroot',
15:28:47 INFO - 'create-virtualenv',
15:28:47 INFO - 'install',
15:28:47 INFO - 'setup-mitmproxy',
15:28:47 INFO - 'run-tests'),
15:28:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
15:28:47 INFO - 'disable_ccov_upload': False,
15:28:47 INFO - 'disable_stylo': False,
15:28:47 INFO - 'download_minidump_stackwalk': True,
15:28:47 INFO - 'download_symbols': 'ondemand',
15:28:47 INFO - 'e10s': False,
15:28:47 INFO - 'enable_stylo': False,
15:28:47 INFO - 'enable_webrender': False,
15:28:47 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
15:28:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
15:28:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
15:28:47 INFO - 'gecko_profile': False,
15:28:47 INFO - 'gecko_profile_interval': 0,
15:28:47 INFO - 'installer_path': 'installer.exe',
15:28:47 INFO - 'jsd_code_coverage': False,
15:28:47 INFO - 'log_level': 'info',
15:28:47 INFO - 'log_name': 'talos',
15:28:47 INFO - 'log_to_console': True,
15:28:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
15:28:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
15:28:47 INFO - 'opt_config_files': (),
15:28:47 INFO - 'pip_index': False,
15:28:47 INFO - 'suite': 'g4-e10s',
15:28:47 INFO - 'system_bits': '32',
15:28:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
15:28:47 INFO - 'title': 'talos-linux64-ix-066',
15:28:47 INFO - 'tooltool_cache': '/builds/tooltool_cache',
15:28:47 INFO - 'use_talos_json': True,
15:28:47 INFO - 'verify': 'False',
15:28:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
15:28:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
15:28:47 INFO - 'work_dir': 'build'}
15:28:47 INFO - [mozharness: 2017-11-11 23:28:47.790226Z] Running clobber step.
15:28:47 INFO - Running pre-action listener: _resource_record_pre_action
15:28:47 INFO - Running main action method: clobber
15:28:47 INFO - rmtree: /builds/slave/test/build
15:28:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
15:28:48 INFO - Running post-action listener: _resource_record_post_action
15:28:48 INFO - [mozharness: 2017-11-11 23:28:48.807419Z] Finished clobber step (success)
15:28:48 INFO - [mozharness: 2017-11-11 23:28:48.807514Z] Running read-buildbot-config step.
15:28:48 INFO - Running pre-action listener: _resource_record_pre_action
15:28:48 INFO - Running main action method: read_buildbot_config
15:28:48 INFO - Using buildbot properties:
15:28:48 INFO - {
15:28:48 INFO - "project": "",
15:28:48 INFO - "product": "firefox",
15:28:48 INFO - "who": "ccoroiu@mozilla.com",
15:28:48 INFO - "installer_path": "public/build/target.tar.bz2",
15:28:48 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
15:28:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
15:28:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-e10s",
15:28:48 INFO - "stage_platform": "linux64-pgo",
15:28:48 INFO - "basedir": "/builds/slave/test",
15:28:48 INFO - "buildnumber": 278,
15:28:48 INFO - "platform": "ubuntu64_hw",
15:28:48 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
15:28:48 INFO - "slavebuilddir": "test",
15:28:48 INFO - "taskId": "OAquT-aDSvO62bTNnI0HUA",
15:28:48 INFO - "branch": "mozilla-central",
15:28:48 INFO - "script_repo_revision": "production",
15:28:48 INFO - "revision": "58b6b371c2f85614ab52dc2d6235e12af020dc1f",
15:28:48 INFO - "slavename": "talos-linux64-ix-066",
15:28:48 INFO - "repo_path": "mozilla-central"
15:28:48 INFO - }
15:28:48 INFO - Finding installer, test and symbols from parent task.
15:28:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OAquT-aDSvO62bTNnI0HUA'}, attempt #1
15:28:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OAquT-aDSvO62bTNnI0HUA'}, attempt #1
15:28:49 INFO - Task dependencies: QLkIi97TSdqv8cULlXiqrg
15:28:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg'}, attempt #1
15:28:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2
15:28:50 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.test_packages.json
15:28:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.crashreporter-symbols.zip
15:28:50 INFO - Running post-action listener: _resource_record_post_action
15:28:50 INFO - [mozharness: 2017-11-11 23:28:50.156202Z] Finished read-buildbot-config step (success)
15:28:50 INFO - [mozharness: 2017-11-11 23:28:50.156418Z] Running download-and-extract step.
15:28:50 INFO - Running pre-action listener: _resource_record_pre_action
15:28:50 INFO - Running main action method: download_and_extract
15:28:50 INFO - mkdir: /builds/slave/test/build/tests
15:28: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')]}
15:28:50 INFO - trying https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.test_packages.json
15:28:50 INFO - Downloading https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
15:28:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
15:28:50 INFO - Downloaded 989 bytes.
15:28:50 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
15:28:50 INFO - Using the following test package requirements:
15:28:50 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
15:28:50 INFO - u'common': [u'target.common.tests.zip'],
15:28:50 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
15:28:50 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
15:28:50 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
15:28:50 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
15:28:50 INFO - u'mozbase': [u'target.common.tests.zip'],
15:28:50 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
15:28:50 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
15:28:50 INFO - u'web-platform': [u'target.common.tests.zip',
15:28:50 INFO - u'target.web-platform.tests.tar.gz'],
15:28:50 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
15:28:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
15:28:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.common.tests.zip
15:28:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.common.tests.zip'}, attempt #1
15:28:50 INFO - Fetch https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.common.tests.zip into memory
15:28:52 INFO - Content-Length response header: 58541392
15:28:52 INFO - Bytes received: 58541392
15:29:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.talos.tests.zip
15:29:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.talos.tests.zip'}, attempt #1
15:29:02 INFO - Fetch https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.talos.tests.zip into memory
15:29:03 INFO - Content-Length response header: 18772073
15:29:03 INFO - Bytes received: 18772073
15:29:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:04 INFO - trying https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2
15:29:04 INFO - Downloading https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
15:29:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
15:29:05 INFO - Downloaded 63314155 bytes.
15:29:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2
15:29:05 INFO - mkdir: /builds/slave/test/properties
15:29:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
15:29:05 INFO - Writing to file /builds/slave/test/properties/build_url
15:29:05 INFO - Contents:
15:29:05 INFO - build_url:https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2
15:29:05 INFO - Running post-action listener: _resource_record_post_action
15:29:05 INFO - Running post-action listener: find_tests_for_verification
15:29:05 INFO - Running post-action listener: set_extra_try_arguments
15:29:05 INFO - [mozharness: 2017-11-11 23:29:05.366761Z] Finished download-and-extract step (success)
15:29:05 INFO - [mozharness: 2017-11-11 23:29:05.366857Z] Running populate-webroot step.
15:29:05 INFO - Running pre-action listener: _resource_record_pre_action
15:29:05 INFO - Running main action method: populate_webroot
15:29:05 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
15:29:05 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['tresize',
15:29:05 INFO - 'tcanvasmark']},
15:29:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
15:29:05 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['dromaeo_css',
15:29:05 INFO - 'kraken']},
15:29:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
15:29:05 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
15:29:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'tests': ['damp', 'tps']},
15:29:05 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['damp', 'tps']},
15:29:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
15:29:05 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['dromaeo_dom']},
15:29:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
15:29:05 INFO - 'glvideo',
15:29:05 INFO - 'displaylist_mutate']},
15:29:05 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['basic_compositor_video',
15:29:05 INFO - 'glvideo']},
15:29:05 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
15:29:05 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['ts_paint_webext',
15:29:05 INFO - 'tp5o_webext']},
15:29:05 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
15:29:05 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
15:29:05 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
15:29:05 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
15:29:05 INFO - 'talos_options': ['--mitmproxy',
15:29:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
15:29:05 INFO - '--firstNonBlankPaint'],
15:29:05 INFO - 'tests': ['tp6_google_heavy',
15:29:05 INFO - 'tp6_youtube_heavy',
15:29:05 INFO - 'tp6_amazon_heavy',
15:29:05 INFO - 'tp6_facebook_heavy']},
15:29:05 INFO - 'other-e10s': {'tests': ['a11yr',
15:29:05 INFO - 'ts_paint',
15:29:05 INFO - 'tpaint',
15:29:05 INFO - 'sessionrestore',
15:29:05 INFO - 'sessionrestore_many_windows',
15:29:05 INFO - 'sessionrestore_no_auto_restore',
15:29:05 INFO - 'tabpaint',
15:29:05 INFO - 'cpstartup']},
15:29:05 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['a11yr',
15:29:05 INFO - 'ts_paint',
15:29:05 INFO - 'tpaint',
15:29:05 INFO - 'sessionrestore',
15:29:05 INFO - 'sessionrestore_many_windows',
15:29:05 INFO - 'sessionrestore_no_auto_restore',
15:29:05 INFO - 'tabpaint',
15:29:05 INFO - 'cpstartup']},
15:29:05 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
15:29:05 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
15:29:05 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['perf_reftest_singletons']},
15:29:05 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['perf_reftest']},
15:29:05 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
15:29:05 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['speedometer']},
15:29:05 INFO - 'svgr-e10s': {'tests': ['tsvgx',
15:29:05 INFO - 'tsvgr_opacity',
15:29:05 INFO - 'tart',
15:29:05 INFO - 'tscrollx',
15:29:05 INFO - 'tsvg_static']},
15:29:05 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['tsvgx',
15:29:05 INFO - 'tsvgr_opacity',
15:29:05 INFO - 'tart',
15:29:05 INFO - 'tscrollx',
15:29:05 INFO - 'tsvg_static']},
15:29:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
15:29:05 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'talos_options': ['--disable-stylo'],
15:29:05 INFO - 'tests': ['tp5o']},
15:29:05 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
15:29:05 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
15:29:05 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
15:29:05 INFO - 'talos_options': ['--mitmproxy',
15:29:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
15:29:05 INFO - '--firstNonBlankPaint'],
15:29:05 INFO - 'tests': ['tp6_google',
15:29:05 INFO - 'tp6_youtube',
15:29:05 INFO - 'tp6_amazon',
15:29:05 INFO - 'tp6_facebook']},
15:29:05 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
15:29:05 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
15:29:05 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
15:29:05 INFO - 'talos_options': ['--disable-stylo',
15:29:05 INFO - '--mitmproxy',
15:29:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
15:29:05 INFO - '--firstNonBlankPaint'],
15:29:05 INFO - 'tests': ['tp6_google',
15:29:05 INFO - 'tp6_youtube',
15:29:05 INFO - 'tp6_amazon',
15:29:05 INFO - 'tp6_facebook']},
15:29:05 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
15:29:05 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
15:29:05 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
15:29:05 INFO - 'talos_options': ['--stylo-threads=1',
15:29:05 INFO - '--mitmproxy',
15:29:05 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
15:29:05 INFO - '--firstNonBlankPaint'],
15:29:05 INFO - 'tests': ['tp6_google',
15:29:05 INFO - 'tp6_youtube',
15:29:05 INFO - 'tp6_amazon',
15:29:05 INFO - 'tp6_facebook']},
15:29:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'talos_options': ['--xperf_path',
15:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:29:05 INFO - 'tests': ['tp5n']},
15:29:05 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
15:29:05 INFO - 'talos_options': ['--disable-stylo',
15:29:05 INFO - '--xperf_path',
15:29:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
15:29:05 INFO - 'tests': ['tp5n']}}}
15:29:05 INFO - Running post-action listener: _resource_record_post_action
15:29:05 INFO - [mozharness: 2017-11-11 23:29:05.385101Z] Finished populate-webroot step (success)
15:29:05 INFO - [mozharness: 2017-11-11 23:29:05.385193Z] Running create-virtualenv step.
15:29:05 INFO - Running pre-action listener: _resource_record_pre_action
15:29:05 INFO - Running main action method: create_virtualenv
15:29:05 INFO - Creating virtualenv /builds/slave/test/build/venv
15:29:05 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
15:29:05 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
15:29:05 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
15:29:08 INFO - Using real prefix '/usr'
15:29:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python
15:29:08 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
15:29:10 INFO - Installing setuptools, pip, wheel...done.
15:29:10 INFO - Return code: 0
15:29:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
15:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
15:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
15:29:10 INFO - Using env: {'DISPLAY': ':0',
15:29:10 INFO - 'HOME': '/home/cltbld',
15:29:10 INFO - 'LANG': 'en_US.UTF-8',
15:29:10 INFO - 'LANGUAGE': 'en_US:en',
15:29:10 INFO - 'LOGNAME': 'cltbld',
15:29:10 INFO - 'MAIL': '/var/mail/cltbld',
15:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:10 INFO - 'MOZ_NO_REMOTE': '1',
15:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:10 INFO - 'NO_EM_RESTART': '1',
15:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:10 INFO - 'PWD': '/builds/slave/test',
15:29:10 INFO - 'SHELL': '/bin/bash',
15:29:10 INFO - 'SHLVL': '1',
15:29:10 INFO - 'TERM': 'linux',
15:29:10 INFO - 'TMOUT': '86400',
15:29:10 INFO - 'USER': 'cltbld',
15:29:10 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:10 INFO - '_': '/tools/buildbot/bin/python'}
15:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
15:29:10 INFO - Return code: 0
15:29:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
15:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
15:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
15:29:10 INFO - Using env: {'DISPLAY': ':0',
15:29:10 INFO - 'HOME': '/home/cltbld',
15:29:10 INFO - 'LANG': 'en_US.UTF-8',
15:29:10 INFO - 'LANGUAGE': 'en_US:en',
15:29:10 INFO - 'LOGNAME': 'cltbld',
15:29:10 INFO - 'MAIL': '/var/mail/cltbld',
15:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:10 INFO - 'MOZ_NO_REMOTE': '1',
15:29:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:10 INFO - 'NO_EM_RESTART': '1',
15:29:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:10 INFO - 'PWD': '/builds/slave/test',
15:29:10 INFO - 'SHELL': '/bin/bash',
15:29:10 INFO - 'SHLVL': '1',
15:29:10 INFO - 'TERM': 'linux',
15:29:10 INFO - 'TMOUT': '86400',
15:29:10 INFO - 'USER': 'cltbld',
15:29:10 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:10 INFO - '_': '/tools/buildbot/bin/python'}
15:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:11 INFO - Collecting psutil>=3.1.1
15:29:12 INFO - Installing collected packages: psutil
15:29:12 INFO - Successfully installed psutil-3.1.1
15:29:12 INFO - Return code: 0
15:29:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
15:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:12 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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:12 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
15:29:12 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
15:29:12 INFO - Using env: {'DISPLAY': ':0',
15:29:12 INFO - 'HOME': '/home/cltbld',
15:29:13 INFO - 'LANG': 'en_US.UTF-8',
15:29:13 INFO - 'LANGUAGE': 'en_US:en',
15:29:13 INFO - 'LOGNAME': 'cltbld',
15:29:13 INFO - 'MAIL': '/var/mail/cltbld',
15:29:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:13 INFO - 'MOZ_NO_REMOTE': '1',
15:29:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:13 INFO - 'NO_EM_RESTART': '1',
15:29:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:13 INFO - 'PWD': '/builds/slave/test',
15:29:13 INFO - 'SHELL': '/bin/bash',
15:29:13 INFO - 'SHLVL': '1',
15:29:13 INFO - 'TERM': 'linux',
15:29:13 INFO - 'TMOUT': '86400',
15:29:13 INFO - 'USER': 'cltbld',
15:29:13 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:13 INFO - '_': '/tools/buildbot/bin/python'}
15:29:13 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:13 INFO - Collecting mozsystemmonitor==0.3
15:29:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
15:29:14 INFO - Installing collected packages: mozsystemmonitor
15:29:14 INFO - Successfully installed mozsystemmonitor-0.3
15:29:14 INFO - Return code: 0
15:29:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
15:29:14 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')]}
15:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:14 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')]}
15:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:14 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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:14 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
15:29:14 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
15:29:14 INFO - Using env: {'DISPLAY': ':0',
15:29:14 INFO - 'HOME': '/home/cltbld',
15:29:14 INFO - 'LANG': 'en_US.UTF-8',
15:29:14 INFO - 'LANGUAGE': 'en_US:en',
15:29:14 INFO - 'LOGNAME': 'cltbld',
15:29:14 INFO - 'MAIL': '/var/mail/cltbld',
15:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:14 INFO - 'MOZ_NO_REMOTE': '1',
15:29:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:14 INFO - 'NO_EM_RESTART': '1',
15:29:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:14 INFO - 'PWD': '/builds/slave/test',
15:29:14 INFO - 'SHELL': '/bin/bash',
15:29:14 INFO - 'SHLVL': '1',
15:29:14 INFO - 'TERM': 'linux',
15:29:14 INFO - 'TMOUT': '86400',
15:29:14 INFO - 'USER': 'cltbld',
15:29:14 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:14 INFO - '_': '/tools/buildbot/bin/python'}
15:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:14 INFO - Collecting jsonschema==2.5.1
15:29:16 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
15:29:16 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
15:29:17 INFO - Installing collected packages: functools32, jsonschema
15:29:18 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
15:29:18 INFO - Return code: 0
15:29:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:18 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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:18 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
15:29:18 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
15:29:18 INFO - Using env: {'DISPLAY': ':0',
15:29:18 INFO - 'HOME': '/home/cltbld',
15:29:18 INFO - 'LANG': 'en_US.UTF-8',
15:29:18 INFO - 'LANGUAGE': 'en_US:en',
15:29:18 INFO - 'LOGNAME': 'cltbld',
15:29:18 INFO - 'MAIL': '/var/mail/cltbld',
15:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:18 INFO - 'MOZ_NO_REMOTE': '1',
15:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:18 INFO - 'NO_EM_RESTART': '1',
15:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:18 INFO - 'PWD': '/builds/slave/test',
15:29:18 INFO - 'SHELL': '/bin/bash',
15:29:18 INFO - 'SHLVL': '1',
15:29:18 INFO - 'TERM': 'linux',
15:29:18 INFO - 'TMOUT': '86400',
15:29:18 INFO - 'USER': 'cltbld',
15:29:18 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:18 INFO - '_': '/tools/buildbot/bin/python'}
15:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
15:29:18 INFO - Return code: 0
15:29:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:18 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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:18 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
15:29:18 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
15:29:18 INFO - Using env: {'DISPLAY': ':0',
15:29:18 INFO - 'HOME': '/home/cltbld',
15:29:18 INFO - 'LANG': 'en_US.UTF-8',
15:29:18 INFO - 'LANGUAGE': 'en_US:en',
15:29:18 INFO - 'LOGNAME': 'cltbld',
15:29:18 INFO - 'MAIL': '/var/mail/cltbld',
15:29:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:18 INFO - 'MOZ_NO_REMOTE': '1',
15:29:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:18 INFO - 'NO_EM_RESTART': '1',
15:29:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:18 INFO - 'PWD': '/builds/slave/test',
15:29:18 INFO - 'SHELL': '/bin/bash',
15:29:18 INFO - 'SHLVL': '1',
15:29:18 INFO - 'TERM': 'linux',
15:29:18 INFO - 'TMOUT': '86400',
15:29:18 INFO - 'USER': 'cltbld',
15:29:18 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:18 INFO - '_': '/tools/buildbot/bin/python'}
15:29:18 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:18 INFO - Collecting blobuploader==1.2.4
15:29:20 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
15:29:21 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
15:29:22 INFO - Installing collected packages: requests, docopt, blobuploader
15:29:22 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
15:29:22 INFO - Return code: 0
15:29:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv
15:29:22 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')]}
15:29:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:22 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')]}
15:29:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:22 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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:22 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
15:29:22 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
15:29:22 INFO - Using env: {'DISPLAY': ':0',
15:29:22 INFO - 'HOME': '/home/cltbld',
15:29:22 INFO - 'LANG': 'en_US.UTF-8',
15:29:22 INFO - 'LANGUAGE': 'en_US:en',
15:29:22 INFO - 'LOGNAME': 'cltbld',
15:29:22 INFO - 'MAIL': '/var/mail/cltbld',
15:29:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:22 INFO - 'MOZ_NO_REMOTE': '1',
15:29:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:22 INFO - 'NO_EM_RESTART': '1',
15:29:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:22 INFO - 'PWD': '/builds/slave/test',
15:29:22 INFO - 'SHELL': '/bin/bash',
15:29:22 INFO - 'SHLVL': '1',
15:29:22 INFO - 'TERM': 'linux',
15:29:22 INFO - 'TMOUT': '86400',
15:29:22 INFO - 'USER': 'cltbld',
15:29:22 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:22 INFO - '_': '/tools/buildbot/bin/python'}
15:29:23 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:23 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
15:29:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
15:29:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
15:29:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
15:29:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
15:29:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
15:29:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
15:29:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
15:29:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
15:29:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
15:29:24 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
15:29:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
15:29:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
15:29:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
15:29:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
15:29:25 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
15:29:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
15:29:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
15:29:26 INFO - Running setup.py install for manifestparser: started
15:29:26 INFO - Running setup.py install for manifestparser: finished with status 'done'
15:29:26 INFO - Running setup.py install for mozcrash: started
15:29:26 INFO - Running setup.py install for mozcrash: finished with status 'done'
15:29:26 INFO - Running setup.py install for mozdebug: started
15:29:26 INFO - Running setup.py install for mozdebug: finished with status 'done'
15:29:26 INFO - Running setup.py install for mozdevice: started
15:29:27 INFO - Running setup.py install for mozdevice: finished with status 'done'
15:29:27 INFO - Running setup.py install for mozfile: started
15:29:27 INFO - Running setup.py install for mozfile: finished with status 'done'
15:29:27 INFO - Running setup.py install for mozhttpd: started
15:29:27 INFO - Running setup.py install for mozhttpd: finished with status 'done'
15:29:27 INFO - Running setup.py install for mozinfo: started
15:29:27 INFO - Running setup.py install for mozinfo: finished with status 'done'
15:29:27 INFO - Running setup.py install for mozInstall: started
15:29:27 INFO - Running setup.py install for mozInstall: finished with status 'done'
15:29:28 INFO - Running setup.py install for mozleak: started
15:29:28 INFO - Running setup.py install for mozleak: finished with status 'done'
15:29:28 INFO - Running setup.py install for mozlog: started
15:29:28 INFO - Running setup.py install for mozlog: finished with status 'done'
15:29:28 INFO - Running setup.py install for moznetwork: started
15:29:28 INFO - Running setup.py install for moznetwork: finished with status 'done'
15:29:28 INFO - Running setup.py install for mozprocess: started
15:29:28 INFO - Running setup.py install for mozprocess: finished with status 'done'
15:29:28 INFO - Running setup.py install for mozprofile: started
15:29:29 INFO - Running setup.py install for mozprofile: finished with status 'done'
15:29:29 INFO - Running setup.py install for mozrunner: started
15:29:29 INFO - Running setup.py install for mozrunner: finished with status 'done'
15:29:29 INFO - Running setup.py install for mozscreenshot: started
15:29:29 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
15:29:29 INFO - Running setup.py install for moztest: started
15:29:29 INFO - Running setup.py install for moztest: finished with status 'done'
15:29:29 INFO - Running setup.py install for mozversion: started
15:29:30 INFO - Running setup.py install for mozversion: finished with status 'done'
15:29:30 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
15:29:30 INFO - Return code: 0
15:29:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv
15:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
15:29:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:30 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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:30 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
15:29:30 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
15:29:30 INFO - Using env: {'DISPLAY': ':0',
15:29:30 INFO - 'HOME': '/home/cltbld',
15:29:30 INFO - 'LANG': 'en_US.UTF-8',
15:29:30 INFO - 'LANGUAGE': 'en_US:en',
15:29:30 INFO - 'LOGNAME': 'cltbld',
15:29:30 INFO - 'MAIL': '/var/mail/cltbld',
15:29:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:30 INFO - 'MOZ_NO_REMOTE': '1',
15:29:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:30 INFO - 'NO_EM_RESTART': '1',
15:29:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:30 INFO - 'PWD': '/builds/slave/test',
15:29:30 INFO - 'SHELL': '/bin/bash',
15:29:30 INFO - 'SHLVL': '1',
15:29:30 INFO - 'TERM': 'linux',
15:29:30 INFO - 'TMOUT': '86400',
15:29:30 INFO - 'USER': 'cltbld',
15:29:30 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:30 INFO - '_': '/tools/buildbot/bin/python'}
15:29:30 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:30 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
15:29:31 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))
15:29:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
15:29:31 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))
15:29:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
15:29:31 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))
15:29:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
15:29:31 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))
15:29:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
15:29:31 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))
15:29:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
15:29:32 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))
15:29:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
15:29:32 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))
15:29:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
15:29:32 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))
15:29:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
15:29:32 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))
15:29:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
15:29:32 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))
15:29:32 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
15:29:32 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))
15:29:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
15:29:33 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))
15:29:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
15:29:33 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))
15:29:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
15:29:33 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))
15:29:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
15:29:33 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))
15:29:33 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
15:29:34 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))
15:29:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
15:29:34 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))
15:29:34 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
15:29:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
15:29:35 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))
15:29:35 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))
15:29:35 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))
15:29:35 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))
15:29:35 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))
15:29:35 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))
15:29:35 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
15:29:36 INFO - Installing collected packages: six, blessings
15:29:36 INFO - Successfully installed blessings-1.6 six-1.10.0
15:29:36 INFO - Return code: 0
15:29:36 INFO - Done creating virtualenv /builds/slave/test/build/venv.
15:29:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
15:29:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
15:29:37 INFO - Reading from file tmpfile_stdout
15:29:37 INFO - Reading from file tmpfile_stderr
15:29:37 INFO - Current package versions:
15:29:37 INFO - blessings == 1.6
15:29:37 INFO - blobuploader == 1.2.4
15:29:37 INFO - docopt == 0.6.1
15:29:37 INFO - functools32 == 3.2.3.post2
15:29:37 INFO - jsonschema == 2.5.1
15:29:37 INFO - manifestparser == 1.2
15:29:37 INFO - mozInstall == 1.14
15:29:37 INFO - mozcrash == 1.0
15:29:37 INFO - mozdebug == 0.1
15:29:37 INFO - mozdevice == 0.51
15:29:37 INFO - mozfile == 1.2
15:29:37 INFO - mozhttpd == 0.7
15:29:37 INFO - mozinfo == 0.10
15:29:37 INFO - mozleak == 0.1
15:29:37 INFO - mozlog == 3.5
15:29:37 INFO - moznetwork == 0.27
15:29:37 INFO - mozprocess == 0.25
15:29:37 INFO - mozprofile == 0.29
15:29:37 INFO - mozrunner == 6.13
15:29:37 INFO - mozscreenshot == 0.1
15:29:37 INFO - mozsystemmonitor == 0.3
15:29:37 INFO - moztest == 0.8
15:29:37 INFO - mozversion == 1.4
15:29:37 INFO - psutil == 3.1.1
15:29:37 INFO - requests == 1.2.3
15:29:37 INFO - six == 1.10.0
15:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv
15:29:37 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')]}
15:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29:37 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')]}
15:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29:37 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 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29:37 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
15:29:37 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
15:29:37 INFO - Using env: {'DISPLAY': ':0',
15:29:37 INFO - 'HOME': '/home/cltbld',
15:29:37 INFO - 'LANG': 'en_US.UTF-8',
15:29:37 INFO - 'LANGUAGE': 'en_US:en',
15:29:37 INFO - 'LOGNAME': 'cltbld',
15:29:37 INFO - 'MAIL': '/var/mail/cltbld',
15:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:37 INFO - 'MOZ_NO_REMOTE': '1',
15:29:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:37 INFO - 'NO_EM_RESTART': '1',
15:29:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:37 INFO - 'PWD': '/builds/slave/test',
15:29:37 INFO - 'SHELL': '/bin/bash',
15:29:37 INFO - 'SHLVL': '1',
15:29:37 INFO - 'TERM': 'linux',
15:29:37 INFO - 'TMOUT': '86400',
15:29:37 INFO - 'USER': 'cltbld',
15:29:37 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:37 INFO - '_': '/tools/buildbot/bin/python'}
15:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:37 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))
15:29:37 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))
15:29:37 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))
15:29:37 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))
15:29:37 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))
15:29:37 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))
15:29:37 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))
15:29:37 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))
15:29:37 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))
15:29:37 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
15:29:38 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
15:29:39 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
15:29:39 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))
15:29:39 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))
15:29:39 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))
15:29:39 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))
15:29:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
15:29:40 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
15:29:41 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
15:29:41 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
15:29:42 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
15:29:42 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
15:29:43 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
15:29:43 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
15:29:44 INFO - Found existing installation: requests 1.2.3
15:29:44 INFO - Uninstalling requests-1.2.3:
15:29:44 INFO - Successfully uninstalled requests-1.2.3
15:29:44 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
15:29:44 INFO - Return code: 0
15:29:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
15:29: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')]}
15:29:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
15:29: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')]}
15:29:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
15:29: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11922d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x136ca80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x150bd70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212', '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
15:29: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', 'jsonschema'] in /builds/slave/test/build
15:29: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 jsonschema
15:29:44 INFO - Using env: {'DISPLAY': ':0',
15:29:44 INFO - 'HOME': '/home/cltbld',
15:29:44 INFO - 'LANG': 'en_US.UTF-8',
15:29:44 INFO - 'LANGUAGE': 'en_US:en',
15:29:44 INFO - 'LOGNAME': 'cltbld',
15:29:44 INFO - 'MAIL': '/var/mail/cltbld',
15:29:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:29:44 INFO - 'MOZ_NO_REMOTE': '1',
15:29:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:29:44 INFO - 'NO_EM_RESTART': '1',
15:29:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:29:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:29:44 INFO - 'PWD': '/builds/slave/test',
15:29:44 INFO - 'SHELL': '/bin/bash',
15:29:44 INFO - 'SHLVL': '1',
15:29:44 INFO - 'TERM': 'linux',
15:29:44 INFO - 'TMOUT': '86400',
15:29:44 INFO - 'USER': 'cltbld',
15:29:44 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:29:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:29:44 INFO - '_': '/tools/buildbot/bin/python'}
15:29:44 INFO - Ignoring indexes: https://pypi.python.org/simple
15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
15:29:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
15:29:44 INFO - Return code: 0
15:29:44 INFO - Running post-action listener: _resource_record_post_action
15:29:44 INFO - Running post-action listener: _start_resource_monitoring
15:29:44 INFO - Starting resource monitoring.
15:29:44 INFO - [mozharness: 2017-11-11 23:29:44.972830Z] Finished create-virtualenv step (success)
15:29:44 INFO - [mozharness: 2017-11-11 23:29:44.973352Z] Running install step.
15:29:44 INFO - Running pre-action listener: _resource_record_pre_action
15:29:44 INFO - Running main action method: install
15:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
15:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
15:29:45 INFO - Reading from file tmpfile_stdout
15:29:45 INFO - Reading from file tmpfile_stderr
15:29:45 INFO - Detecting whether we're running mozinstall >=1.0...
15:29:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
15:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
15:29:45 INFO - Reading from file tmpfile_stdout
15:29:45 INFO - Output received:
15:29:45 INFO - Usage: mozinstall [options] installer
15:29:45 INFO - Options:
15:29:45 INFO - -h, --help show this help message and exit
15:29:45 INFO - -d DEST, --destination=DEST
15:29:45 INFO - Directory to install application into. [default:
15:29:45 INFO - "/builds/slave/test"]
15:29:45 INFO - --app=APP Application being installed. [default: firefox]
15:29:45 INFO - mkdir: /builds/slave/test/build/application
15:29:45 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']
15:29:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
15:30:03 INFO - Reading from file tmpfile_stdout
15:30:03 INFO - Output received:
15:30:03 INFO - /builds/slave/test/build/application/firefox/firefox
15:30:03 INFO - Running post-action listener: _resource_record_post_action
15:30:03 INFO - [mozharness: 2017-11-11 23:30:03.783977Z] Finished install step (success)
15:30:03 INFO - [mozharness: 2017-11-11 23:30:03.784223Z] Running setup-mitmproxy step.
15:30:03 INFO - Running pre-action listener: _resource_record_pre_action
15:30:03 INFO - Running main action method: setup_mitmproxy
15:30:03 INFO - Skipping: mitmproxy is not required
15:30:03 INFO - Running post-action listener: _resource_record_post_action
15:30:03 INFO - [mozharness: 2017-11-11 23:30:03.785405Z] Finished setup-mitmproxy step (success)
15:30:03 INFO - [mozharness: 2017-11-11 23:30:03.785623Z] Running run-tests step.
15:30:03 INFO - Running pre-action listener: _resource_record_pre_action
15:30:03 INFO - Running pre-action listener: _set_gcov_prefix
15:30:03 INFO - Running main action method: run_tests
15:30:03 WARNING - Try message not found.
15:30:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
15:30:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
15:30:03 INFO - Python 2.7.3
15:30:03 INFO - Return code: 0
15:30:03 INFO - grabbing minidump binary from tooltool
15:30:03 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')]}
15:30:03 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 0x150f070>, '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 0x1510080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x150a2a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
15:30:03 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
15:30:03 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
15:30:03 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
15:30:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
15:30:04 INFO - Return code: 0
15:30:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
15:30:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
15:30:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
15:30:04 INFO - ENV: RUST_BACKTRACE is now full
15:30:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
15:30:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
15:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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
15:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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
15:30:04 INFO - Using env: {'DISPLAY': ':0',
15:30:04 INFO - 'HOME': '/home/cltbld',
15:30:04 INFO - 'LANG': 'en_US.UTF-8',
15:30:04 INFO - 'LANGUAGE': 'en_US:en',
15:30:04 INFO - 'LOGNAME': 'cltbld',
15:30:04 INFO - 'MAIL': '/var/mail/cltbld',
15:30:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
15:30:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
15:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:30:04 INFO - 'MOZ_NO_REMOTE': '1',
15:30:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
15:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:30:04 INFO - 'NO_EM_RESTART': '1',
15:30:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:30:04 INFO - 'PWD': '/builds/slave/test',
15:30:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
15:30:04 INFO - 'RUST_BACKTRACE': 'full',
15:30:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
15:30:04 INFO - 'SHELL': '/bin/bash',
15:30:04 INFO - 'SHLVL': '1',
15:30:04 INFO - 'TERM': 'linux',
15:30:04 INFO - 'TMOUT': '86400',
15:30:04 INFO - 'USER': 'cltbld',
15:30:04 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:30:04 INFO - '_': '/tools/buildbot/bin/python'}
15:30:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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
15:30:04 INFO - mozversion application_buildid: 20171111220356
15:30:04 INFO - mozversion application_changeset: 58b6b371c2f85614ab52dc2d6235e12af020dc1f
15:30:04 INFO - mozversion application_display_name: Nightly
15:30:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
15:30:04 INFO - mozversion application_name: Firefox
15:30:04 INFO - mozversion application_remotingname: firefox
15:30:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
15:30:04 INFO - mozversion application_vendor: Mozilla
15:30:04 INFO - mozversion application_version: 58.0a1
15:30:04 INFO - mozversion platform_buildid: 20171111220356
15:30:04 INFO - mozversion platform_changeset: 58b6b371c2f85614ab52dc2d6235e12af020dc1f
15:30:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
15:30:04 INFO - mozversion platform_version: 58.0a1
15:30:04 INFO - using testdate: 1510443004
15:30:04 INFO - actual date: 1510443004
15:30:04 INFO - starting webserver on 'localhost:53011'
15:30:04 INFO - SUITE-START | Running 3 tests
15:30:04 INFO - TEST-START | basic_compositor_video
15:30:04 INFO - Initialising browser for basic_compositor_video test...
15:30:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53011/getInfo.html -profile /tmp/tmpQyHVbO/profile
15:30:04 INFO - TEST-INFO | started process 3760 (/builds/slave/test/build/application/firefox/firefox http://localhost:53011/getInfo.html)
15:30:12 INFO - TEST-INFO | 3760: exit 0
15:30:12 INFO - Browser initialized.
15:30:12 INFO - Running cycle 1/1 for basic_compositor_video test...
15:30:12 INFO - Using env: {'DISPLAY': ':0',
15:30:12 INFO - 'HOME': '/home/cltbld',
15:30:12 INFO - 'JSGC_DISABLE_POISONING': '1',
15:30:12 INFO - 'LANG': 'en_US.UTF-8',
15:30:12 INFO - 'LANGUAGE': 'en_US:en',
15:30:12 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
15:30:12 INFO - 'LOGNAME': 'cltbld',
15:30:12 INFO - 'MAIL': '/var/mail/cltbld',
15:30:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
15:30:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
15:30:12 INFO - 'MOZ_CRASHREPORTER': '1',
15:30:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:30:12 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
15:30:12 INFO - 'MOZ_NO_REMOTE': '1',
15:30:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
15:30:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:30:12 INFO - 'NO_EM_RESTART': '1',
15:30:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:30:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:30:12 INFO - 'PWD': '/builds/slave/test',
15:30:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
15:30:12 INFO - 'RUST_BACKTRACE': 'full',
15:30:12 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
15:30:12 INFO - 'SHELL': '/bin/bash',
15:30:12 INFO - 'SHLVL': '1',
15:30:12 INFO - 'TERM': 'linux',
15:30:12 INFO - 'TMOUT': '86400',
15:30:12 INFO - 'USER': 'cltbld',
15:30:12 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:30:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:30:12 INFO - '_': '/tools/buildbot/bin/python'}
15:30:12 INFO - TEST-INFO | started process 3997 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQyHVbO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
15:30:13 INFO - PID 3997 |
15:30:13 INFO - PID 3997 | (/builds/slave/test/build/application/firefox/firefox:4048): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
15:30:13 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6762877094972068 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6697495826377295 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7263147295742227 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6910608622147096 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.005574098798397 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9534288537549434 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.4574251152073754 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.407964224872229 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.64061090225564 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.588575418994411 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.529245614035085 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.05213567839196 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.99016483516483 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.700561497326229 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.29540983606558 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907202380952402 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.321584905660382 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.530210526315779 ms/frame
15:31:00 INFO - PID 3997 |
15:31:00 INFO - PID 3997 | Cycle 1(1): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:31:46 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6799300111982083 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6695075125208678 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.747513104251602 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7582864674868184 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.173511821974964 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.033598790322582 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.7001171393341554 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.591876122082584 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704077946768056 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.511212121212121 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.52801754385965 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.104696969696967 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.834223826714808 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.205688775510179 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.300102459016417 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83967455621301 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.280620300751867 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.872581521739132 ms/frame
15:31:46 INFO - PID 3997 |
15:31:46 INFO - PID 3997 | Cycle 1(2): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:32:32 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6812100840336135 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668940783986656 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7660005885815189 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.757557117750439 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116172839506171 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.238919491525425 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.6816748466257674 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.650474452554743 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.651233521657251 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.526339779005524 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.346310344827588 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.209999999999999 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.951733576642328 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639069148936192 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.452593360995838 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.91136904761905 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.3664015151515 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.049972375690597 ms/frame
15:32:32 INFO - PID 3997 |
15:32:32 INFO - PID 3997 | Cycle 1(3): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:33:18 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6870236087689714 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6655911740216482 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7535359438924607 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.745929319371727 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0437061994609165 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1327995867768585 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.642057038834952 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.558816725978648 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.694724857685007 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.650607344632775 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.1047138047138 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 9.857561576354675 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.072693726937285 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.417239583333336 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.401053719008265 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90479166666665 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.27885338345863 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.758118279569903 ms/frame
15:33:18 INFO - PID 3997 |
15:33:18 INFO - PID 3997 | Cycle 1(4): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:34:04 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810952380952382 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6730560200668896 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7136407766990291 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7087574722459444 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.094345156889496 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.992964071856288 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.496824009324009 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.442401032702237 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672608695652176 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572506963788298 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.138682432432443 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.313298969072159 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.99184981684981 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.366010362694297 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398119834710748 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83476331360945 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.364924242424228 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932786885245886 ms/frame
15:34:04 INFO - PID 3997 |
15:34:04 INFO - PID 3997 | Cycle 1(5): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:34:50 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6769815539407489 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.67004590984975 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.723400344629523 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.703901192504259 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.179066852367687 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.142670807453416 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.513700234192036 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.402338435374151 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693358633776087 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5427700831025 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.239778156996591 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 9.709733009708728 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.07145756457565 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.753978494623645 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659240506329136 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90732142857143 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.27849624060151 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.873994565217364 ms/frame
15:34:50 INFO - PID 3997 |
15:34:50 INFO - PID 3997 | Cycle 1(6): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:35:36 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6779893736017897 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6711027568922305 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.6991024915062287 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7044207836456564 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.065433604336042 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.133429752066116 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.563800475059384 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.448887931034482 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.649802259887003 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.604915966386559 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 9.616394230769238 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.362461139896363 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.071531365313648 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.587592592592594 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.501687499999996 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.767264705882383 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540288461538456 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.998461538461545 ms/frame
15:35:36 INFO - PID 3997 |
15:35:36 INFO - PID 3997 | Cycle 1(7): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:36:23 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6760893854748604 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689991659716432 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7205389908256885 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6939881456392882 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 3.886081606217617 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9477514792899413 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.437245131729668 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3900593220338955 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.69296963946869 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5558611111111125 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.490472027972032 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.209005102040818 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.951532846715319 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527394736842114 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500500000000011 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.838639053254413 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49704980842913 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.753279569892475 ms/frame
15:36:23 INFO - PID 3997 |
15:36:23 INFO - PID 3997 | Cycle 1(8): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:37:09 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822421524663675 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6696410684474123 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7254312823461764 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6909298393913779 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093963165075034 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.099026639344264 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.50553738317757 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.431054888507719 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672145557655952 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.4054729729729765 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.000783333333334 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.20538265306123 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.953850364963502 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.475523560209448 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.552740585774073 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053222891566259 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.15444237918215 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.474214659685886 ms/frame
15:37:09 INFO - PID 3997 |
15:37:09 INFO - PID 3997 | Cycle 1(9): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:37:55 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6781599552572708 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669000000000003 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7475101921956904 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7156560891938257 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.098900273224045 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158066528066529 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.695548029556648 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6505109489051155 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.649971751412426 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.604901960784316 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.172491525423723 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.154796954314735 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991849816849822 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640930851063844 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348559670781912 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.561618497109805 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.237902621722835 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.699144385026766 ms/frame
15:37:55 INFO - PID 3997 |
15:37:55 INFO - PID 3997 | Cycle 1(10): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:38:41 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.688466516601013 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6724832775919736 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7066780432309439 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6935478408128712 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.060067658998646 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0737169042769885 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.542178276269184 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4670190641247856 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.576691449814123 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.450054495912812 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.346241379310344 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.101742424242426 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.94990875912408 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.526736842105272 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.658924050632884 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90458333333334 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.153661710037172 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11111111111111 ms/frame
15:38:41 INFO - PID 3997 |
15:38:41 INFO - PID 3997 | Cycle 1(11): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:39:27 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6792501398992725 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6715580618212198 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7466210710128054 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7215576592082622 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116069958847737 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.134028925619833 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_startup = 3.6768566176470596 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1_inclip = 3.579794275491949 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5983302238806 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.571963788300833 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_startup = 10.456271777003487 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.480p.60fps.webm_scale_2_inclip = 10.2577435897436 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.754587813620068 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.258333333333333 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.449253112033212 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835680473372756 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.321396226415088 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87114130434783 ms/frame
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | Cycle 1(12): loaded http://localhost:53011/tests/video/video_playback.html (next: http://localhost:53011/tests/video/video_playback.html)
15:39:27 INFO - PID 3997 | __start_tp_report
15:39:27 INFO - PID 3997 | _x_x_mozilla_page_load
15:39:27 INFO - PID 3997 | _x_x_mozilla_page_load_details
15:39:27 INFO - PID 3997 | |i|pagename|runs|
15:39:27 INFO - PID 3997 | |0;240p.120fps.mp4_scale_1_startup;1.6762877094972068;1.6799300111982083;1.6812100840336135;1.6870236087689714;1.6810952380952382;1.6769815539407489;1.6779893736017897;1.6760893854748604;1.6822421524663675;1.6781599552572708;1.688466516601013;1.6792501398992725
15:39:27 INFO - PID 3997 | |1;240p.120fps.mp4_scale_1_inclip;1.6697495826377295;1.6695075125208678;1.668940783986656;1.6655911740216482;1.6730560200668896;1.67004590984975;1.6711027568922305;1.6689991659716432;1.6696410684474123;1.6669000000000003;1.6724832775919736;1.6715580618212198
15:39:27 INFO - PID 3997 | |2;240p.120fps.mp4_scale_1.1_startup;1.7263147295742227;1.747513104251602;1.7660005885815189;1.7535359438924607;1.7136407766990291;1.723400344629523;1.6991024915062287;1.7205389908256885;1.7254312823461764;1.7475101921956904;1.7066780432309439;1.7466210710128054
15:39:27 INFO - PID 3997 | |3;240p.120fps.mp4_scale_1.1_inclip;1.6910608622147096;1.7582864674868184;1.757557117750439;1.745929319371727;1.7087574722459444;1.703901192504259;1.7044207836456564;1.6939881456392882;1.6909298393913779;1.7156560891938257;1.6935478408128712;1.7215576592082622
15:39:27 INFO - PID 3997 | |4;240p.120fps.mp4_scale_2_startup;4.005574098798397;4.173511821974964;4.116172839506171;4.0437061994609165;4.094345156889496;4.179066852367687;4.065433604336042;3.886081606217617;4.093963165075034;4.098900273224045;4.060067658998646;4.116069958847737
15:39:27 INFO - PID 3997 | |5;240p.120fps.mp4_scale_2_inclip;3.9534288537549434;4.033598790322582;4.238919491525425;4.1327995867768585;3.992964071856288;4.142670807453416;4.133429752066116;3.9477514792899413;4.099026639344264;4.158066528066529;4.0737169042769885;4.134028925619833
15:39:27 INFO - PID 3997 | |6;480p.60fps.webm_scale_1_startup;3.4574251152073754;3.7001171393341554;3.6816748466257674;3.642057038834952;3.496824009324009;3.513700234192036;3.563800475059384;3.437245131729668;3.50553738317757;3.695548029556648;3.542178276269184;3.6768566176470596
15:39:27 INFO - PID 3997 | |7;480p.60fps.webm_scale_1_inclip;3.407964224872229;3.591876122082584;3.650474452554743;3.558816725978648;3.442401032702237;3.402338435374151;3.448887931034482;3.3900593220338955;3.431054888507719;3.6505109489051155;3.4670190641247856;3.579794275491949
15:39:27 INFO - PID 3997 | |8;480p.60fps.webm_scale_1.1_startup;5.64061090225564;5.704077946768056;5.651233521657251;5.694724857685007;5.672608695652176;5.693358633776087;5.649802259887003;5.69296963946869;5.672145557655952;5.649971751412426;5.576691449814123;5.5983302238806
15:39:27 INFO - PID 3997 | |9;480p.60fps.webm_scale_1.1_inclip;5.588575418994411;5.511212121212121;5.526339779005524;5.650607344632775;5.572506963788298;5.5427700831025;5.604915966386559;5.5558611111111125;5.4054729729729765;5.604901960784316;5.450054495912812;5.571963788300833
15:39:27 INFO - PID 3997 | |10;480p.60fps.webm_scale_2_startup;10.529245614035085;10.52801754385965;10.346310344827588;10.1047138047138;10.138682432432443;10.239778156996591;9.616394230769238;10.490472027972032;10.000783333333334;10.172491525423723;10.346241379310344;10.456271777003487
15:39:27 INFO - PID 3997 | |11;480p.60fps.webm_scale_2_inclip;10.05213567839196;10.104696969696967;10.209999999999999;9.857561576354675;10.313298969072159;9.709733009708728;10.362461139896363;10.209005102040818;10.20538265306123;10.154796954314735;10.101742424242426;10.2577435897436
15:39:27 INFO - PID 3997 | |12;1080p.60fps.mp4_scale_1_startup;10.99016483516483;10.834223826714808;10.951733576642328;11.072693726937285;10.99184981684981;11.07145756457565;11.071531365313648;10.951532846715319;10.953850364963502;10.991849816849822;10.94990875912408;10.754587813620068
15:39:27 INFO - PID 3997 | |13;1080p.60fps.mp4_scale_1_inclip;10.700561497326229;10.205688775510179;10.639069148936192;10.417239583333336;10.366010362694297;10.753978494623645;10.587592592592594;10.527394736842114;10.475523560209448;10.640930851063844;10.526736842105272;10.258333333333333
15:39:27 INFO - PID 3997 | |14;1080p.60fps.mp4_scale_1.1_startup;12.29540983606558;12.300102459016417;12.452593360995838;12.401053719008265;12.398119834710748;12.659240506329136;12.501687499999996;12.500500000000011;12.552740585774073;12.348559670781912;12.658924050632884;12.449253112033212
15:39:27 INFO - PID 3997 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.907202380952402;11.83967455621301;11.91136904761905;11.90479166666665;11.83476331360945;11.90732142857143;11.767264705882383;11.838639053254413;12.053222891566259;11.561618497109805;11.90458333333334;11.835680473372756
15:39:27 INFO - PID 3997 | |16;1080p.60fps.mp4_scale_2_startup;11.321584905660382;11.280620300751867;11.3664015151515;11.27885338345863;11.364924242424228;11.27849624060151;11.540288461538456;11.49704980842913;11.15444237918215;11.237902621722835;11.153661710037172;11.321396226415088
15:39:27 INFO - PID 3997 | |17;1080p.60fps.mp4_scale_2_inclip;10.530210526315779;10.872581521739132;11.049972375690597;10.758118279569903;10.932786885245886;10.873994565217364;10.998461538461545;10.753279569892475;10.474214659685886;10.699144385026766;11.11111111111111;10.87114130434783
15:39:27 INFO - PID 3997 | __end_tp_report
15:39:27 INFO - PID 3997 | __start_cc_report
15:39:27 INFO - PID 3997 | _x_x_mozilla_cycle_collect,537
15:39:27 INFO - PID 3997 | __end_cc_report
15:39:27 INFO - PID 3997 | __startTimestamp1510443567951__endTimestamp
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | ------- Summary: start -------
15:39:27 INFO - PID 3997 | Number of tests: 18
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
15:39:27 INFO - PID 3997 | 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
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#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
15:39:27 INFO - PID 3997 | 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
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.73 Median:1.74 stddev:0.02 (1.2%) stddev-sans-first:0.02
15:39:27 INFO - PID 3997 | Values: 1.7 1.7 1.8 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.72 Median:1.71 stddev:0.03 (1.5%) stddev-sans-first:0.03
15:39:27 INFO - PID 3997 | Values: 1.7 1.8 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.08 Median:4.10 stddev:0.08 (1.9%) stddev-sans-first:0.08
15:39:27 INFO - PID 3997 | Values: 4.0 4.2 4.1 4.0 4.1 4.2 4.1 3.9 4.1 4.1 4.1 4.1
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.09 Median:4.13 stddev:0.09 (2.2%) stddev-sans-first:0.08
15:39:27 INFO - PID 3997 | Values: 4.0 4.0 4.2 4.1 4.0 4.1 4.1 3.9 4.1 4.2 4.1 4.1
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.58 Median:3.60 stddev:0.10 (2.7%) stddev-sans-first:0.09
15:39:27 INFO - PID 3997 | Values: 3.5 3.7 3.7 3.6 3.5 3.5 3.6 3.4 3.5 3.7 3.5 3.7
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.50 Median:3.51 stddev:0.10 (2.8%) stddev-sans-first:0.10
15:39:27 INFO - PID 3997 | Values: 3.4 3.6 3.7 3.6 3.4 3.4 3.4 3.4 3.4 3.7 3.5 3.6
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.66 Median:5.67 stddev:0.04 (0.7%) stddev-sans-first:0.04
15:39:27 INFO - PID 3997 | Values: 5.6 5.7 5.7 5.7 5.7 5.7 5.6 5.7 5.7 5.6 5.6 5.6
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.55 Median:5.57 stddev:0.07 (1.2%) stddev-sans-first:0.07
15:39:27 INFO - PID 3997 | Values: 5.6 5.5 5.5 5.7 5.6 5.5 5.6 5.6 5.4 5.6 5.5 5.6
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.25 Median:10.40 stddev:0.27 (2.6%) stddev-sans-first:0.26
15:39:27 INFO - PID 3997 | Values: 10.5 10.5 10.3 10.1 10.1 10.2 9.6 10.5 10.0 10.2 10.3 10.5
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.13 Median:10.23 stddev:0.19 (1.8%) stddev-sans-first:0.19
15:39:27 INFO - PID 3997 | Values: 10.1 10.1 10.2 9.9 10.3 9.7 10.4 10.2 10.2 10.2 10.1 10.3
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:10.97 Median:10.99 stddev:0.09 (0.9%) stddev-sans-first:0.10
15:39:27 INFO - PID 3997 | Values: 11.0 10.8 11.0 11.1 11.0 11.1 11.1 11.0 11.0 11.0 10.9 10.8
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.51 Median:10.56 stddev:0.17 (1.6%) stddev-sans-first:0.17
15:39:27 INFO - PID 3997 | Values: 10.7 10.2 10.6 10.4 10.4 10.8 10.6 10.5 10.5 10.6 10.5 10.3
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.46 Median:12.48 stddev:0.12 (1.0%) stddev-sans-first:0.12
15:39:27 INFO - PID 3997 | Values: 12.3 12.3 12.5 12.4 12.4 12.7 12.5 12.5 12.6 12.3 12.7 12.4
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.86 Median:11.90 stddev:0.12 (1.0%) stddev-sans-first:0.12
15:39:27 INFO - PID 3997 | Values: 11.9 11.8 11.9 11.9 11.8 11.9 11.8 11.8 12.1 11.6 11.9 11.8
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.32 Median:11.32 stddev:0.12 (1.0%) stddev-sans-first:0.12
15:39:27 INFO - PID 3997 | Values: 11.3 11.3 11.4 11.3 11.4 11.3 11.5 11.5 11.2 11.2 11.2 11.3
15:39:27 INFO - PID 3997 |
15:39:27 INFO - PID 3997 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.83 Median:10.87 stddev:0.19 (1.8%) stddev-sans-first:0.18
15:39:27 INFO - PID 3997 | Values: 10.5 10.9 11.0 10.8 10.9 10.9 11.0 10.8 10.5 10.7 11.1 10.9
15:39:27 INFO - PID 3997 | -------- Summary: end --------
15:39:27 INFO - PID 3997 |
15:39:28 INFO - TEST-INFO | 3997: exit 0
15:39:28 INFO - TEST-OK | basic_compositor_video | took 563876ms
15:39:28 INFO - TEST-START | glvideo
15:39:28 INFO - Initialising browser for glvideo test...
15:39:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53011/getInfo.html -profile /tmp/tmpCLj1q2/profile
15:39:28 INFO - TEST-INFO | started process 5253 (/builds/slave/test/build/application/firefox/firefox http://localhost:53011/getInfo.html)
15:39:34 INFO - TEST-INFO | 5253: exit 0
15:39:34 INFO - Browser initialized.
15:39:34 INFO - Running cycle 1/1 for glvideo test...
15:39:34 INFO - Using env: {'DISPLAY': ':0',
15:39:34 INFO - 'HOME': '/home/cltbld',
15:39:34 INFO - 'JSGC_DISABLE_POISONING': '1',
15:39:34 INFO - 'LANG': 'en_US.UTF-8',
15:39:34 INFO - 'LANGUAGE': 'en_US:en',
15:39:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
15:39:34 INFO - 'LOGNAME': 'cltbld',
15:39:34 INFO - 'MAIL': '/var/mail/cltbld',
15:39:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
15:39:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
15:39:34 INFO - 'MOZ_CRASHREPORTER': '1',
15:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:39:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
15:39:34 INFO - 'MOZ_NO_REMOTE': '1',
15:39:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
15:39:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:39:34 INFO - 'NO_EM_RESTART': '1',
15:39:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:39:34 INFO - 'PWD': '/builds/slave/test',
15:39:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
15:39:34 INFO - 'RUST_BACKTRACE': 'full',
15:39:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
15:39:34 INFO - 'SHELL': '/bin/bash',
15:39:34 INFO - 'SHLVL': '1',
15:39:34 INFO - 'TERM': 'linux',
15:39:34 INFO - 'TMOUT': '86400',
15:39:34 INFO - 'USER': 'cltbld',
15:39:34 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:39:34 INFO - '_': '/tools/buildbot/bin/python'}
15:39:34 INFO - TEST-INFO | started process 5498 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCLj1q2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
15:39:34 INFO - PID 5498 |
15:39:34 INFO - PID 5498 | (/builds/slave/test/build/application/firefox/firefox:5551): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
15:39:34 INFO - PID 5498 |
15:39:36 INFO - PID 5498 | [talos glvideo result] Mean tick time across 100 ticks: 7.552850000000001 ms
15:39:36 INFO - PID 5498 | Cycle 1(1): loaded http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html)
15:39:37 INFO - PID 5498 | [talos glvideo result] Mean tick time across 100 ticks: 6.53505 ms
15:39:37 INFO - PID 5498 | Cycle 1(2): loaded http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html)
15:39:39 INFO - PID 5498 | [talos glvideo result] Mean tick time across 100 ticks: 6.589000000000001 ms
15:39:39 INFO - PID 5498 | Cycle 1(3): loaded http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html)
15:39:40 INFO - PID 5498 | [talos glvideo result] Mean tick time across 100 ticks: 6.65485 ms
15:39:40 INFO - PID 5498 | Cycle 1(4): loaded http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html)
15:39:41 INFO - PID 5498 | [talos glvideo result] Mean tick time across 100 ticks: 6.5209 ms
15:39:41 INFO - PID 5498 | Cycle 1(5): loaded http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53011/tests/webgl/benchmarks/video/video_upload.html)
15:39:42 INFO - PID 5498 | __start_tp_report
15:39:42 INFO - PID 5498 | _x_x_mozilla_page_load
15:39:42 INFO - PID 5498 | _x_x_mozilla_page_load_details
15:39:42 INFO - PID 5498 | |i|pagename|runs|
15:39:42 INFO - PID 5498 | |0;Mean tick time across 100 ticks: ;7.552850000000001;6.53505;6.589000000000001;6.65485;6.5209
15:39:42 INFO - PID 5498 | __end_tp_report
15:39:42 INFO - PID 5498 | __start_cc_report
15:39:42 INFO - PID 5498 | _x_x_mozilla_cycle_collect,262
15:39:42 INFO - PID 5498 | __end_cc_report
15:39:42 INFO - PID 5498 | __startTimestamp1510443582028__endTimestamp
15:39:42 INFO - PID 5498 |
15:39:42 INFO - PID 5498 | ------- Summary: start -------
15:39:42 INFO - PID 5498 | Number of tests: 1
15:39:42 INFO - PID 5498 |
15:39:42 INFO - PID 5498 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.77 Median:6.59 stddev:0.44 (6.7%) stddev-sans-first:0.06
15:39:42 INFO - PID 5498 | Values: 7.6 6.5 6.6 6.7 6.5
15:39:42 INFO - PID 5498 | -------- Summary: end --------
15:39:42 INFO - PID 5498 |
15:39:42 INFO - PID 5498 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
15:39:42 INFO - TEST-INFO | 5498: exit 0
15:39:42 INFO - TEST-OK | glvideo | took 14140ms
15:39:42 INFO - TEST-START | displaylist_mutate
15:39:42 INFO - Initialising browser for displaylist_mutate test...
15:39:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53011/getInfo.html -profile /tmp/tmpCOFEfD/profile
15:39:42 INFO - TEST-INFO | started process 5721 (/builds/slave/test/build/application/firefox/firefox http://localhost:53011/getInfo.html)
15:39:48 INFO - TEST-INFO | 5721: exit 0
15:39:48 INFO - Browser initialized.
15:39:48 INFO - Running cycle 1/1 for displaylist_mutate test...
15:39:48 INFO - Using env: {'DISPLAY': ':0',
15:39:48 INFO - 'HOME': '/home/cltbld',
15:39:48 INFO - 'JSGC_DISABLE_POISONING': '1',
15:39:48 INFO - 'LANG': 'en_US.UTF-8',
15:39:48 INFO - 'LANGUAGE': 'en_US:en',
15:39:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
15:39:48 INFO - 'LOGNAME': 'cltbld',
15:39:48 INFO - 'MAIL': '/var/mail/cltbld',
15:39:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
15:39:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
15:39:48 INFO - 'MOZ_CRASHREPORTER': '1',
15:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
15:39:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
15:39:48 INFO - 'MOZ_NO_REMOTE': '1',
15:39:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
15:39:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
15:39:48 INFO - 'NO_EM_RESTART': '1',
15:39:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
15:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
15:39:48 INFO - 'PWD': '/builds/slave/test',
15:39:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
15:39:48 INFO - 'RUST_BACKTRACE': 'full',
15:39:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
15:39:48 INFO - 'SHELL': '/bin/bash',
15:39:48 INFO - 'SHLVL': '1',
15:39:48 INFO - 'TERM': 'linux',
15:39:48 INFO - 'TMOUT': '86400',
15:39:48 INFO - 'USER': 'cltbld',
15:39:48 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212',
15:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
15:39:48 INFO - '_': '/tools/buildbot/bin/python'}
15:39:48 INFO - TEST-INFO | started process 5962 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCOFEfD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
15:39:49 INFO - PID 5962 |
15:39:49 INFO - PID 5962 | (/builds/slave/test/build/application/firefox/firefox:6013): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
15:39:49 INFO - PID 5962 |
15:40:07 INFO - PID 5962 | Cycle 1(1): loaded http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html)
15:40:08 INFO - PID 5962 | Waiting for idle-callback
15:40:27 INFO - PID 5962 | Cycle 1(2): loaded http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html)
15:40:27 INFO - PID 5962 | Waiting for idle-callback
15:40:46 INFO - PID 5962 | Cycle 1(3): loaded http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html)
15:41:05 INFO - PID 5962 | Cycle 1(4): loaded http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html)
15:41:24 INFO - PID 5962 | Cycle 1(5): loaded http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53011/tests/layout/benchmarks/displaylist_mutate.html)
15:41:24 INFO - PID 5962 | __start_tp_report
15:41:24 INFO - PID 5962 | _x_x_mozilla_page_load
15:41:24 INFO - PID 5962 | _x_x_mozilla_page_load_details
15:41:24 INFO - PID 5962 | |i|pagename|runs|
15:41:24 INFO - PID 5962 | |0;/displaylist_mutate.html;17757.065;18123.83;18210.325;18186.665;18161.530000000002
15:41:24 INFO - PID 5962 | __end_tp_report
15:41:24 INFO - PID 5962 | __start_cc_report
15:41:24 INFO - PID 5962 | _x_x_mozilla_cycle_collect,209
15:41:24 INFO - PID 5962 | __end_cc_report
15:41:24 INFO - PID 5962 | __startTimestamp1510443684864__endTimestamp
15:41:24 INFO - PID 5962 |
15:41:24 INFO - PID 5962 | ------- Summary: start -------
15:41:24 INFO - PID 5962 | Number of tests: 1
15:41:24 INFO - PID 5962 |
15:41:24 INFO - PID 5962 | [#0] /displaylist_mutate.html Cycles:5 Average:18087.88 Median:18161.53 stddev:187.69 (1.0%) stddev-sans-first:36.99
15:41:24 INFO - PID 5962 | Values: 17757.1 18123.8 18210.3 18186.7 18161.5
15:41:24 INFO - PID 5962 | -------- Summary: end --------
15:41:24 INFO - PID 5962 |
15:41:25 INFO - TEST-INFO | 5962: exit 0
15:41:25 INFO - TEST-OK | displaylist_mutate | took 102804ms
15:41:25 INFO - SUITE-END | took 680s
15:41:25 INFO - Completed test suite (00:11:21)
15:41:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.582460062397176, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6762877094972068, 1.6799300111982083, 1.6812100840336135, 1.6870236087689714, 1.6810952380952382, 1.6769815539407489, 1.6779893736017897, 1.6760893854748604, 1.6822421524663675, 1.6781599552572708, 1.688466516601013, 1.6792501398992725], "value": 1.6799300111982083, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6697495826377295, 1.6695075125208678, 1.668940783986656, 1.6655911740216482, 1.6730560200668896, 1.67004590984975, 1.6711027568922305, 1.6689991659716432, 1.6696410684474123, 1.6669000000000003, 1.6724832775919736, 1.6715580618212198], "value": 1.6696410684474123, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7263147295742227, 1.747513104251602, 1.7660005885815189, 1.7535359438924607, 1.7136407766990291, 1.723400344629523, 1.6991024915062287, 1.7205389908256885, 1.7254312823461764, 1.7475101921956904, 1.7066780432309439, 1.7466210710128054], "value": 1.7254312823461764, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6910608622147096, 1.7582864674868184, 1.757557117750439, 1.745929319371727, 1.7087574722459444, 1.703901192504259, 1.7044207836456564, 1.6939881456392882, 1.6909298393913779, 1.7156560891938257, 1.6935478408128712, 1.7215576592082622], "value": 1.7087574722459444, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.005574098798397, 4.173511821974964, 4.116172839506171, 4.0437061994609165, 4.094345156889496, 4.179066852367687, 4.065433604336042, 3.886081606217617, 4.093963165075034, 4.098900273224045, 4.060067658998646, 4.116069958847737], "value": 4.094345156889496, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9534288537549434, 4.033598790322582, 4.238919491525425, 4.1327995867768585, 3.992964071856288, 4.142670807453416, 4.133429752066116, 3.9477514792899413, 4.099026639344264, 4.158066528066529, 4.0737169042769885, 4.134028925619833], "value": 4.1327995867768585, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4574251152073754, 3.7001171393341554, 3.6816748466257674, 3.642057038834952, 3.496824009324009, 3.513700234192036, 3.563800475059384, 3.437245131729668, 3.50553738317757, 3.695548029556648, 3.542178276269184, 3.6768566176470596], "value": 3.563800475059384, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.407964224872229, 3.591876122082584, 3.650474452554743, 3.558816725978648, 3.442401032702237, 3.402338435374151, 3.448887931034482, 3.3900593220338955, 3.431054888507719, 3.6505109489051155, 3.4670190641247856, 3.579794275491949], "value": 3.4670190641247856, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.64061090225564, 5.704077946768056, 5.651233521657251, 5.694724857685007, 5.672608695652176, 5.693358633776087, 5.649802259887003, 5.69296963946869, 5.672145557655952, 5.649971751412426, 5.576691449814123, 5.5983302238806], "value": 5.672145557655952, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.588575418994411, 5.511212121212121, 5.526339779005524, 5.650607344632775, 5.572506963788298, 5.5427700831025, 5.604915966386559, 5.5558611111111125, 5.4054729729729765, 5.604901960784316, 5.450054495912812, 5.571963788300833], "value": 5.5558611111111125, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.529245614035085, 10.52801754385965, 10.346310344827588, 10.1047138047138, 10.138682432432443, 10.239778156996591, 9.616394230769238, 10.490472027972032, 10.000783333333334, 10.172491525423723, 10.346241379310344, 10.456271777003487], "value": 10.239778156996591, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.05213567839196, 10.104696969696967, 10.209999999999999, 9.857561576354675, 10.313298969072159, 9.709733009708728, 10.362461139896363, 10.209005102040818, 10.20538265306123, 10.154796954314735, 10.101742424242426, 10.2577435897436], "value": 10.20538265306123, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.99016483516483, 10.834223826714808, 10.951733576642328, 11.072693726937285, 10.99184981684981, 11.07145756457565, 11.071531365313648, 10.951532846715319, 10.953850364963502, 10.991849816849822, 10.94990875912408, 10.754587813620068], "value": 10.953850364963502, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.700561497326229, 10.205688775510179, 10.639069148936192, 10.417239583333336, 10.366010362694297, 10.753978494623645, 10.587592592592594, 10.527394736842114, 10.475523560209448, 10.640930851063844, 10.526736842105272, 10.258333333333333], "value": 10.526736842105272, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.29540983606558, 12.300102459016417, 12.452593360995838, 12.401053719008265, 12.398119834710748, 12.659240506329136, 12.501687499999996, 12.500500000000011, 12.552740585774073, 12.348559670781912, 12.658924050632884, 12.449253112033212], "value": 12.452593360995838, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.907202380952402, 11.83967455621301, 11.91136904761905, 11.90479166666665, 11.83476331360945, 11.90732142857143, 11.767264705882383, 11.838639053254413, 12.053222891566259, 11.561618497109805, 11.90458333333334, 11.835680473372756], "value": 11.83967455621301, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.321584905660382, 11.280620300751867, 11.3664015151515, 11.27885338345863, 11.364924242424228, 11.27849624060151, 11.540288461538456, 11.49704980842913, 11.15444237918215, 11.237902621722835, 11.153661710037172, 11.321396226415088], "value": 11.280620300751867, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.530210526315779, 10.872581521739132, 11.049972375690597, 10.758118279569903, 10.932786885245886, 10.873994565217364, 10.998461538461545, 10.753279569892475, 10.474214659685886, 10.699144385026766, 11.11111111111111, 10.87114130434783], "value": 10.872581521739132, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.552850000000001, 6.53505, 6.589000000000001, 6.65485, 6.5209], "value": 6.562025, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [17757.065, 18123.83, 18210.325, 18186.665, 18161.530000000002], "value": 18174.097500000003, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
15:41:25 INFO - Return code: 0
15:41:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
15:41:25 INFO - # TBPL SUCCESS #
15:41:25 INFO - Running post-action listener: _package_coverage_data
15:41:25 INFO - Running post-action listener: _resource_record_post_action
15:41:25 INFO - [mozharness: 2017-11-11 23:41:25.985214Z] Finished run-tests step (success)
15:41:25 INFO - Running post-run listener: _resource_record_post_run
15:41:26 INFO - Total resource usage - Wall time: 701s; CPU: 35.0%; Read bytes: 18313216; Write bytes: 344928256; Read time: 3824; Write time: 318692
15:41:26 INFO - TinderboxPrint: CPU usage
35.0%
15:41:26 INFO - TinderboxPrint: I/O read bytes / time
18,313,216 / 3,824
15:41:26 INFO - TinderboxPrint: I/O write bytes / time
344,928,256 / 318,692
15:41:26 INFO - TinderboxPrint: CPU idle
3,582.7 (65.0%)
15:41:26 INFO - TinderboxPrint: CPU system
320.5 (5.8%)
15:41:26 INFO - TinderboxPrint: CPU user
1,581.5 (28.7%)
15:41:26 INFO - TinderboxPrint: Swap in / out
0 / 0
15:41:26 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 34435072; Read time: 0; Write time: 31560
15:41:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
15:41:26 INFO - run-tests - Wall time: 682s; CPU: 36.0%; Read bytes: 18296832; Write bytes: 222232576; Read time: 3820; Write time: 177448
15:41:26 INFO - Running post-run listener: _upload_blobber_files
15:41:26 INFO - Blob upload gear active.
15:41:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
15:41:26 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
15:41:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
15:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
15:41:26 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
15:41:26 INFO - SNIMissingWarning
15:41:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
15:41:26 INFO - InsecurePlatformWarning
15:41:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
15:41:26 INFO - SubjectAltNameWarning
15:41:26 INFO - (blobuploader) - INFO - Open directory for files ...
15:41:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
15:41:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:41:26 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:41:26 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
15:41:26 INFO - InsecurePlatformWarning
15:41:27 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.)
15:41:27 INFO - SubjectAltNameWarning
15:41:27 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
15:41:27 INFO - InsecurePlatformWarning
15:41:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
15:41:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:41:27 INFO - (blobuploader) - INFO - Done attempting.
15:41:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
15:41:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:41:27 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:41:27 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
15:41:27 INFO - InsecurePlatformWarning
15:41:27 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.)
15:41:27 INFO - SubjectAltNameWarning
15:41:33 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
15:41:33 INFO - InsecurePlatformWarning
15:41:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
15:41:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:41:33 INFO - (blobuploader) - INFO - Done attempting.
15:41:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
15:41:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:41:33 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:41:33 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
15:41:33 INFO - InsecurePlatformWarning
15:41:33 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.)
15:41:33 INFO - SubjectAltNameWarning
15:41:34 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
15:41:34 INFO - InsecurePlatformWarning
15:41:34 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
15:41:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:41:34 INFO - (blobuploader) - INFO - Done attempting.
15:41:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
15:41:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
15:41:34 INFO - (blobuploader) - INFO - Uploading, attempt #1.
15:41:34 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
15:41:34 INFO - InsecurePlatformWarning
15:41:34 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.)
15:41:34 INFO - SubjectAltNameWarning
15:41:35 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
15:41:35 INFO - InsecurePlatformWarning
15:41:36 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
15:41:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
15:41:36 INFO - (blobuploader) - INFO - Done attempting.
15:41:36 INFO - (blobuploader) - INFO - Iteration through files over.
15:41:36 INFO - Return code: 0
15:41:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
15:41:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
15:41:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934d6c9729e5a5aee65fb4f80f525723087a6f2c51e2bc56ad28d15fdd236c497ed46504975f2c09a034dccda398d9185afd5d448c3e5ca2d33a67ee0c1d888", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1b3b5556dd4a9309abb35642a6615c729d9ec8162c9cc197ffb1b8e3ad00e2de2bd0e04e63137294cb8d5a9f94f24475511603eeea30a5f5d5e156bd4c2c95", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/569029d94afd5cc4dfe19d4fc76ffd2abb13cf4675ec7b09688980437bfd9ee54b034524f68c19694c694f60fb3e494766606af1de617e709a3312fff97601db", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae975a44c0646aedc1bfa73073015e29bb338705afd56512f9e7919789324b8db25b5f94e885bd0f404280324200e1097d9889c29242bf01cb21921aba0fc4b5"}
15:41:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
15:41:36 INFO - Writing to file /builds/slave/test/properties/blobber_files
15:41:36 INFO - Contents:
15:41:36 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934d6c9729e5a5aee65fb4f80f525723087a6f2c51e2bc56ad28d15fdd236c497ed46504975f2c09a034dccda398d9185afd5d448c3e5ca2d33a67ee0c1d888", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1b3b5556dd4a9309abb35642a6615c729d9ec8162c9cc197ffb1b8e3ad00e2de2bd0e04e63137294cb8d5a9f94f24475511603eeea30a5f5d5e156bd4c2c95", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/569029d94afd5cc4dfe19d4fc76ffd2abb13cf4675ec7b09688980437bfd9ee54b034524f68c19694c694f60fb3e494766606af1de617e709a3312fff97601db", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae975a44c0646aedc1bfa73073015e29bb338705afd56512f9e7919789324b8db25b5f94e885bd0f404280324200e1097d9889c29242bf01cb21921aba0fc4b5"}
15:41:36 INFO - Running post-run listener: copy_logs_to_upload_dir
15:41:36 INFO - Copying logs to upload dir...
15:41:36 INFO - mkdir: /builds/slave/test/build/upload/logs
15:41:36 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=768.523506
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 48 secs) (at 2017-11-11 15:41:36.093144) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-11 15:41:36.099146) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934d6c9729e5a5aee65fb4f80f525723087a6f2c51e2bc56ad28d15fdd236c497ed46504975f2c09a034dccda398d9185afd5d448c3e5ca2d33a67ee0c1d888", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1b3b5556dd4a9309abb35642a6615c729d9ec8162c9cc197ffb1b8e3ad00e2de2bd0e04e63137294cb8d5a9f94f24475511603eeea30a5f5d5e156bd4c2c95", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/569029d94afd5cc4dfe19d4fc76ffd2abb13cf4675ec7b09688980437bfd9ee54b034524f68c19694c694f60fb3e494766606af1de617e709a3312fff97601db", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae975a44c0646aedc1bfa73073015e29bb338705afd56512f9e7919789324b8db25b5f94e885bd0f404280324200e1097d9889c29242bf01cb21921aba0fc4b5"}
build_url:https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011246
build_url: 'https://queue.taskcluster.net/v1/task/QLkIi97TSdqv8cULlXiqrg/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7934d6c9729e5a5aee65fb4f80f525723087a6f2c51e2bc56ad28d15fdd236c497ed46504975f2c09a034dccda398d9185afd5d448c3e5ca2d33a67ee0c1d888", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5d1b3b5556dd4a9309abb35642a6615c729d9ec8162c9cc197ffb1b8e3ad00e2de2bd0e04e63137294cb8d5a9f94f24475511603eeea30a5f5d5e156bd4c2c95", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/569029d94afd5cc4dfe19d4fc76ffd2abb13cf4675ec7b09688980437bfd9ee54b034524f68c19694c694f60fb3e494766606af1de617e709a3312fff97601db", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ae975a44c0646aedc1bfa73073015e29bb338705afd56512f9e7919789324b8db25b5f94e885bd0f404280324200e1097d9889c29242bf01cb21921aba0fc4b5"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-11 15:41:36.133874) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 15:41:36.134343) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510442924.588585-834327212
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004566
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-11 15:41:36.217536) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-11 15:41:36.218038) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-11 15:41:36.218464) =========
========= Total master_lag: 0.22 =========