builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-056
starttime: 1512070714.27
results: success (0)
revision: a3a5f13265750c9bd6589cc61ebbffceeea476a4
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.272821) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.273265) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.273584) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.386268) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.386633) =========
bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1512070273.598434-74646057
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-30 11:38:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 103M=0s
2017-11-30 11:38:34 (103 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.092416
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.520558) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.520861) =========
rm -rf scripts properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1512070273.598434-74646057
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020683
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:34.586541) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-30 11:38:34.586870) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a3a5f13265750c9bd6589cc61ebbffceeea476a4 --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 a3a5f13265750c9bd6589cc61ebbffceeea476a4 --destination scripts --debug']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1512070273.598434-74646057
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-30 11:38:34,643 truncating revision to first 12 chars
2017-11-30 11:38:34,643 Setting DEBUG logging.
2017-11-30 11:38:34,643 attempt 1/10
2017-11-30 11:38:34,644 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a3a5f1326575?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-30 11:38:35,046 unpacking tar archive at: mozilla-central-a3a5f1326575/testing/mozharness/
program finished with exit code 0
elapsedTime=1.010589
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-30 11:38:35.618304) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:35.618636) =========
script_repo_revision: a3a5f13265750c9bd6589cc61ebbffceeea476a4
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:35.619099) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:35.619407) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-30 11:38:35.637155) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 9 secs) (at 2017-11-30 11:38:35.637469) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1512070273.598434-74646057
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
11:38:35 INFO - MultiFileLogger online at 20171130 11:38:35 in /builds/slave/test
11:38:35 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
11:38:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
11:38:35 INFO - {'append_to_log': False,
11:38:35 INFO - 'base_work_dir': '/builds/slave/test',
11:38:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
11:38:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
11:38:35 INFO - 'branch': 'Firefox-Non-PGO',
11:38:35 INFO - 'buildbot_json_path': 'buildprops.json',
11:38:35 INFO - 'code_coverage': False,
11:38:35 INFO - 'config_files': ('talos/linux_config.py',),
11:38:35 INFO - 'default_actions': ('clobber',
11:38:35 INFO - 'read-buildbot-config',
11:38:35 INFO - 'download-and-extract',
11:38:35 INFO - 'populate-webroot',
11:38:35 INFO - 'create-virtualenv',
11:38:35 INFO - 'install',
11:38:35 INFO - 'setup-mitmproxy',
11:38:35 INFO - 'run-tests'),
11:38:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
11:38:35 INFO - 'disable_ccov_upload': False,
11:38:35 INFO - 'disable_stylo': False,
11:38:35 INFO - 'download_minidump_stackwalk': True,
11:38:35 INFO - 'download_symbols': 'ondemand',
11:38:35 INFO - 'e10s': False,
11:38:35 INFO - 'enable_stylo': False,
11:38:35 INFO - 'enable_webrender': False,
11:38:35 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
11:38:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
11:38:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
11:38:35 INFO - 'gecko_profile': False,
11:38:35 INFO - 'gecko_profile_interval': 0,
11:38:35 INFO - 'installer_path': 'installer.exe',
11:38:35 INFO - 'jsd_code_coverage': False,
11:38:35 INFO - 'log_level': 'info',
11:38:35 INFO - 'log_name': 'talos',
11:38:35 INFO - 'log_to_console': True,
11:38:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
11:38:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
11:38:35 INFO - 'opt_config_files': (),
11:38:35 INFO - 'pip_index': False,
11:38:35 INFO - 'suite': 'g4-e10s',
11:38:35 INFO - 'system_bits': '32',
11:38:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
11:38:35 INFO - 'title': 'talos-linux64-ix-056',
11:38:35 INFO - 'tooltool_cache': '/builds/tooltool_cache',
11:38:35 INFO - 'use_talos_json': True,
11:38:35 INFO - 'verify': 'False',
11:38:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
11:38:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
11:38:35 INFO - 'work_dir': 'build'}
11:38:35 INFO - [mozharness: 2017-11-30 19:38:35.832180Z] Running clobber step.
11:38:35 INFO - Running pre-action listener: _resource_record_pre_action
11:38:35 INFO - Running main action method: clobber
11:38:35 INFO - rmtree: /builds/slave/test/build
11:38:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
11:38:36 INFO - Running post-action listener: _resource_record_post_action
11:38:36 INFO - [mozharness: 2017-11-30 19:38:36.927582Z] Finished clobber step (success)
11:38:36 INFO - [mozharness: 2017-11-30 19:38:36.927678Z] Running read-buildbot-config step.
11:38:36 INFO - Running pre-action listener: _resource_record_pre_action
11:38:36 INFO - Running main action method: read_buildbot_config
11:38:36 INFO - Using buildbot properties:
11:38:36 INFO - {
11:38:36 INFO - "project": "",
11:38:36 INFO - "product": "firefox",
11:38:36 INFO - "who": "ffxbld@noreply.mozilla.org",
11:38:36 INFO - "installer_path": "public/build/target.tar.bz2",
11:38:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
11:38:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
11:38:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
11:38:36 INFO - "stage_platform": "linux64",
11:38:36 INFO - "basedir": "/builds/slave/test",
11:38:36 INFO - "buildnumber": 877,
11:38:36 INFO - "platform": "ubuntu64_hw",
11:38:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
11:38:36 INFO - "slavebuilddir": "test",
11:38:36 INFO - "taskId": "Emc37vLvRIyqZx4npjc-QQ",
11:38:36 INFO - "branch": "mozilla-central",
11:38:36 INFO - "script_repo_revision": "production",
11:38:36 INFO - "revision": "a3a5f13265750c9bd6589cc61ebbffceeea476a4",
11:38:36 INFO - "slavename": "talos-linux64-ix-056",
11:38:36 INFO - "repo_path": "mozilla-central"
11:38:36 INFO - }
11:38:36 INFO - Finding installer, test and symbols from parent task.
11:38:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Emc37vLvRIyqZx4npjc-QQ'}, attempt #1
11:38:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Emc37vLvRIyqZx4npjc-QQ'}, attempt #1
11:38:37 INFO - Task dependencies: HX9pUEUdTfarWvM71BOdMQ
11:38:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ'}, attempt #1
11:38:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2
11:38:38 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.test_packages.json
11:38:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.crashreporter-symbols.zip
11:38:38 INFO - Running post-action listener: _resource_record_post_action
11:38:38 INFO - [mozharness: 2017-11-30 19:38:38.286771Z] Finished read-buildbot-config step (success)
11:38:38 INFO - [mozharness: 2017-11-30 19:38:38.286987Z] Running download-and-extract step.
11:38:38 INFO - Running pre-action listener: _resource_record_pre_action
11:38:38 INFO - Running main action method: download_and_extract
11:38:38 INFO - mkdir: /builds/slave/test/build/tests
11:38:38 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')]}
11:38:38 INFO - trying https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.test_packages.json
11:38:38 INFO - Downloading https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
11:38:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
11:38:38 INFO - Downloaded 989 bytes.
11:38:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
11:38:38 INFO - Using the following test package requirements:
11:38:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
11:38:38 INFO - u'common': [u'target.common.tests.zip'],
11:38:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
11:38:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
11:38:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
11:38:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
11:38:38 INFO - u'mozbase': [u'target.common.tests.zip'],
11:38:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
11:38:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
11:38:38 INFO - u'web-platform': [u'target.common.tests.zip',
11:38:38 INFO - u'target.web-platform.tests.tar.gz'],
11:38:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
11:38:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
11:38:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.common.tests.zip
11:38:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
11:38:38 INFO - Fetch https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.common.tests.zip into memory
11:38:40 INFO - Content-Length response header: 59346667
11:38:40 INFO - Bytes received: 59346667
11:38:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.talos.tests.zip
11:38:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
11:38:51 INFO - Fetch https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.talos.tests.zip into memory
11:38:51 INFO - Content-Length response header: 18744804
11:38:51 INFO - Bytes received: 18744804
11:38:52 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')]}
11:38:52 INFO - trying https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2
11:38:52 INFO - Downloading https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
11:38:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
11:38:53 INFO - Downloaded 62766059 bytes.
11:38:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2
11:38:53 INFO - mkdir: /builds/slave/test/properties
11:38:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
11:38:53 INFO - Writing to file /builds/slave/test/properties/build_url
11:38:53 INFO - Contents:
11:38:53 INFO - build_url:https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2
11:38:53 INFO - Running post-action listener: _resource_record_post_action
11:38:53 INFO - Running post-action listener: find_tests_for_verification
11:38:53 INFO - Running post-action listener: set_extra_try_arguments
11:38:53 INFO - [mozharness: 2017-11-30 19:38:53.479072Z] Finished download-and-extract step (success)
11:38:53 INFO - [mozharness: 2017-11-30 19:38:53.479167Z] Running populate-webroot step.
11:38:53 INFO - Running pre-action listener: _resource_record_pre_action
11:38:53 INFO - Running main action method: populate_webroot
11:38:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
11:38:53 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['tresize',
11:38:53 INFO - 'tcanvasmark']},
11:38:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
11:38:53 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['dromaeo_css',
11:38:53 INFO - 'kraken']},
11:38:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
11:38:53 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
11:38:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'tests': ['damp', 'tps']},
11:38:53 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['damp', 'tps']},
11:38:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
11:38:53 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['dromaeo_dom']},
11:38:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
11:38:53 INFO - 'glvideo',
11:38:53 INFO - 'displaylist_mutate']},
11:38:53 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['basic_compositor_video',
11:38:53 INFO - 'glvideo']},
11:38:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
11:38:53 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['ts_paint_webext',
11:38:53 INFO - 'tp5o_webext']},
11:38:53 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
11:38:53 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:38:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:38:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:38:53 INFO - 'talos_options': ['--mitmproxy',
11:38:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:38:53 INFO - '--firstNonBlankPaint'],
11:38:53 INFO - 'tests': ['tp6_google_heavy',
11:38:53 INFO - 'tp6_youtube_heavy',
11:38:53 INFO - 'tp6_amazon_heavy',
11:38:53 INFO - 'tp6_facebook_heavy']},
11:38:53 INFO - 'other-e10s': {'tests': ['a11yr',
11:38:53 INFO - 'ts_paint',
11:38:53 INFO - 'tpaint',
11:38:53 INFO - 'sessionrestore',
11:38:53 INFO - 'sessionrestore_many_windows',
11:38:53 INFO - 'sessionrestore_no_auto_restore',
11:38:53 INFO - 'tabpaint',
11:38:53 INFO - 'cpstartup']},
11:38:53 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['a11yr',
11:38:53 INFO - 'ts_paint',
11:38:53 INFO - 'tpaint',
11:38:53 INFO - 'sessionrestore',
11:38:53 INFO - 'sessionrestore_many_windows',
11:38:53 INFO - 'sessionrestore_no_auto_restore',
11:38:53 INFO - 'tabpaint',
11:38:53 INFO - 'cpstartup']},
11:38:53 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
11:38:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
11:38:53 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['perf_reftest_singletons']},
11:38:53 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['perf_reftest']},
11:38:53 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
11:38:53 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['speedometer']},
11:38:53 INFO - 'svgr-e10s': {'tests': ['tsvgx',
11:38:53 INFO - 'tsvgr_opacity',
11:38:53 INFO - 'tart',
11:38:53 INFO - 'tscrollx',
11:38:53 INFO - 'tsvg_static']},
11:38:53 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['tsvgx',
11:38:53 INFO - 'tsvgr_opacity',
11:38:53 INFO - 'tart',
11:38:53 INFO - 'tscrollx',
11:38:53 INFO - 'tsvg_static']},
11:38:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
11:38:53 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'talos_options': ['--disable-stylo'],
11:38:53 INFO - 'tests': ['tp5o']},
11:38:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:38:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:38:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:38:53 INFO - 'talos_options': ['--mitmproxy',
11:38:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:38:53 INFO - '--firstNonBlankPaint'],
11:38:53 INFO - 'tests': ['tp6_google',
11:38:53 INFO - 'tp6_youtube',
11:38:53 INFO - 'tp6_amazon',
11:38:53 INFO - 'tp6_facebook']},
11:38:53 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:38:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:38:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:38:53 INFO - 'talos_options': ['--disable-stylo',
11:38:53 INFO - '--mitmproxy',
11:38:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:38:53 INFO - '--firstNonBlankPaint'],
11:38:53 INFO - 'tests': ['tp6_google',
11:38:53 INFO - 'tp6_youtube',
11:38:53 INFO - 'tp6_amazon',
11:38:53 INFO - 'tp6_facebook']},
11:38:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
11:38:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
11:38:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
11:38:53 INFO - 'talos_options': ['--stylo-threads=1',
11:38:53 INFO - '--mitmproxy',
11:38:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
11:38:53 INFO - '--firstNonBlankPaint'],
11:38:53 INFO - 'tests': ['tp6_google',
11:38:53 INFO - 'tp6_youtube',
11:38:53 INFO - 'tp6_amazon',
11:38:53 INFO - 'tp6_facebook']},
11:38:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'talos_options': ['--xperf_path',
11:38:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
11:38:53 INFO - 'tests': ['tp5n']},
11:38:53 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
11:38:53 INFO - 'talos_options': ['--disable-stylo',
11:38:53 INFO - '--xperf_path',
11:38:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
11:38:53 INFO - 'tests': ['tp5n']}}}
11:38:53 INFO - Running post-action listener: _resource_record_post_action
11:38:53 INFO - [mozharness: 2017-11-30 19:38:53.499044Z] Finished populate-webroot step (success)
11:38:53 INFO - [mozharness: 2017-11-30 19:38:53.499138Z] Running create-virtualenv step.
11:38:53 INFO - Running pre-action listener: _resource_record_pre_action
11:38:53 INFO - Running main action method: create_virtualenv
11:38:53 INFO - Creating virtualenv /builds/slave/test/build/venv
11:38:53 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
11:38:53 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
11:38:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
11:38:54 INFO - Using real prefix '/usr'
11:38:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python
11:38:54 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
11:38:56 INFO - Installing setuptools, pip, wheel...done.
11:38:56 INFO - Return code: 0
11:38:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
11:38:56 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')]}
11:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:38:56 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')]}
11:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:38:56 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 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:38:56 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
11:38:56 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
11:38:56 INFO - Using env: {'DISPLAY': ':0',
11:38:56 INFO - 'HOME': '/home/cltbld',
11:38:56 INFO - 'LANG': 'en_US.UTF-8',
11:38:56 INFO - 'LANGUAGE': 'en_US:en',
11:38:56 INFO - 'LOGNAME': 'cltbld',
11:38:56 INFO - 'MAIL': '/var/mail/cltbld',
11:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:38:56 INFO - 'MOZ_NO_REMOTE': '1',
11:38:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:38:56 INFO - 'NO_EM_RESTART': '1',
11:38:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:38:56 INFO - 'PWD': '/builds/slave/test',
11:38:56 INFO - 'SHELL': '/bin/bash',
11:38:56 INFO - 'SHLVL': '1',
11:38:56 INFO - 'TERM': 'linux',
11:38:56 INFO - 'TMOUT': '86400',
11:38:56 INFO - 'USER': 'cltbld',
11:38:56 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:38:56 INFO - '_': '/tools/buildbot/bin/python'}
11:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple
11:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
11:38:57 INFO - Return code: 0
11:38:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
11:38:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:38:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:38:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:38:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:38:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
11:38:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
11:38:57 INFO - Using env: {'DISPLAY': ':0',
11:38:57 INFO - 'HOME': '/home/cltbld',
11:38:57 INFO - 'LANG': 'en_US.UTF-8',
11:38:57 INFO - 'LANGUAGE': 'en_US:en',
11:38:57 INFO - 'LOGNAME': 'cltbld',
11:38:57 INFO - 'MAIL': '/var/mail/cltbld',
11:38:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:38:57 INFO - 'MOZ_NO_REMOTE': '1',
11:38:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:38:57 INFO - 'NO_EM_RESTART': '1',
11:38:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:38:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:38:57 INFO - 'PWD': '/builds/slave/test',
11:38:57 INFO - 'SHELL': '/bin/bash',
11:38:57 INFO - 'SHLVL': '1',
11:38:57 INFO - 'TERM': 'linux',
11:38:57 INFO - 'TMOUT': '86400',
11:38:57 INFO - 'USER': 'cltbld',
11:38:57 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:38:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:38:57 INFO - '_': '/tools/buildbot/bin/python'}
11:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple
11:38:57 INFO - Collecting psutil>=3.1.1
11:38:58 INFO - Installing collected packages: psutil
11:38:58 INFO - Successfully installed psutil-3.1.1
11:38:59 INFO - Return code: 0
11:38:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
11:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:38:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:38:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
11:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
11:38:59 INFO - Using env: {'DISPLAY': ':0',
11:38:59 INFO - 'HOME': '/home/cltbld',
11:38:59 INFO - 'LANG': 'en_US.UTF-8',
11:38:59 INFO - 'LANGUAGE': 'en_US:en',
11:38:59 INFO - 'LOGNAME': 'cltbld',
11:38:59 INFO - 'MAIL': '/var/mail/cltbld',
11:38:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:38:59 INFO - 'MOZ_NO_REMOTE': '1',
11:38:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:38:59 INFO - 'NO_EM_RESTART': '1',
11:38:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:38:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:38:59 INFO - 'PWD': '/builds/slave/test',
11:38:59 INFO - 'SHELL': '/bin/bash',
11:38:59 INFO - 'SHLVL': '1',
11:38:59 INFO - 'TERM': 'linux',
11:38:59 INFO - 'TMOUT': '86400',
11:38:59 INFO - 'USER': 'cltbld',
11:38:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:38:59 INFO - '_': '/tools/buildbot/bin/python'}
11:38:59 INFO - Ignoring indexes: https://pypi.python.org/simple
11:38:59 INFO - Collecting mozsystemmonitor==0.3
11:39:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
11:39:00 INFO - Installing collected packages: mozsystemmonitor
11:39:00 INFO - Successfully installed mozsystemmonitor-0.3
11:39:01 INFO - Return code: 0
11:39:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
11:39:01 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')]}
11:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:39:01 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')]}
11:39:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:39:01 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 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:39:01 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
11:39:01 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
11:39:01 INFO - Using env: {'DISPLAY': ':0',
11:39:01 INFO - 'HOME': '/home/cltbld',
11:39:01 INFO - 'LANG': 'en_US.UTF-8',
11:39:01 INFO - 'LANGUAGE': 'en_US:en',
11:39:01 INFO - 'LOGNAME': 'cltbld',
11:39:01 INFO - 'MAIL': '/var/mail/cltbld',
11:39:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:01 INFO - 'MOZ_NO_REMOTE': '1',
11:39:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:01 INFO - 'NO_EM_RESTART': '1',
11:39:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:01 INFO - 'PWD': '/builds/slave/test',
11:39:01 INFO - 'SHELL': '/bin/bash',
11:39:01 INFO - 'SHLVL': '1',
11:39:01 INFO - 'TERM': 'linux',
11:39:01 INFO - 'TMOUT': '86400',
11:39:01 INFO - 'USER': 'cltbld',
11:39:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:01 INFO - '_': '/tools/buildbot/bin/python'}
11:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple
11:39:01 INFO - Collecting jsonschema==2.5.1
11:39:02 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
11:39:02 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
11:39:04 INFO - Installing collected packages: functools32, jsonschema
11:39:04 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
11:39:04 INFO - Return code: 0
11:39:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
11:39: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')]}
11:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:39: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')]}
11:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:39:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:39:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
11:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
11:39:04 INFO - Using env: {'DISPLAY': ':0',
11:39:04 INFO - 'HOME': '/home/cltbld',
11:39:04 INFO - 'LANG': 'en_US.UTF-8',
11:39:04 INFO - 'LANGUAGE': 'en_US:en',
11:39:04 INFO - 'LOGNAME': 'cltbld',
11:39:04 INFO - 'MAIL': '/var/mail/cltbld',
11:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:04 INFO - 'MOZ_NO_REMOTE': '1',
11:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:04 INFO - 'NO_EM_RESTART': '1',
11:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:04 INFO - 'PWD': '/builds/slave/test',
11:39:04 INFO - 'SHELL': '/bin/bash',
11:39:04 INFO - 'SHLVL': '1',
11:39:04 INFO - 'TERM': 'linux',
11:39:04 INFO - 'TMOUT': '86400',
11:39:04 INFO - 'USER': 'cltbld',
11:39:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:04 INFO - '_': '/tools/buildbot/bin/python'}
11:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple
11:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
11:39:04 INFO - Return code: 0
11:39:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
11:39: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')]}
11:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:39: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')]}
11:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:39:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:39:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
11:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
11:39:04 INFO - Using env: {'DISPLAY': ':0',
11:39:04 INFO - 'HOME': '/home/cltbld',
11:39:04 INFO - 'LANG': 'en_US.UTF-8',
11:39:04 INFO - 'LANGUAGE': 'en_US:en',
11:39:04 INFO - 'LOGNAME': 'cltbld',
11:39:04 INFO - 'MAIL': '/var/mail/cltbld',
11:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:04 INFO - 'MOZ_NO_REMOTE': '1',
11:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:04 INFO - 'NO_EM_RESTART': '1',
11:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:04 INFO - 'PWD': '/builds/slave/test',
11:39:04 INFO - 'SHELL': '/bin/bash',
11:39:04 INFO - 'SHLVL': '1',
11:39:04 INFO - 'TERM': 'linux',
11:39:04 INFO - 'TMOUT': '86400',
11:39:04 INFO - 'USER': 'cltbld',
11:39:04 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:04 INFO - '_': '/tools/buildbot/bin/python'}
11:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple
11:39:05 INFO - Collecting blobuploader==1.2.4
11:39:06 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
11:39:07 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
11:39:08 INFO - Installing collected packages: requests, docopt, blobuploader
11:39:08 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
11:39:08 INFO - Return code: 0
11:39:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:39:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:39:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:39:08 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 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:39:08 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
11:39:08 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
11:39:08 INFO - Using env: {'DISPLAY': ':0',
11:39:08 INFO - 'HOME': '/home/cltbld',
11:39:08 INFO - 'LANG': 'en_US.UTF-8',
11:39:08 INFO - 'LANGUAGE': 'en_US:en',
11:39:08 INFO - 'LOGNAME': 'cltbld',
11:39:08 INFO - 'MAIL': '/var/mail/cltbld',
11:39:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:08 INFO - 'MOZ_NO_REMOTE': '1',
11:39:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:08 INFO - 'NO_EM_RESTART': '1',
11:39:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:08 INFO - 'PWD': '/builds/slave/test',
11:39:08 INFO - 'SHELL': '/bin/bash',
11:39:08 INFO - 'SHLVL': '1',
11:39:08 INFO - 'TERM': 'linux',
11:39:08 INFO - 'TMOUT': '86400',
11:39:08 INFO - 'USER': 'cltbld',
11:39:08 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:08 INFO - '_': '/tools/buildbot/bin/python'}
11:39:09 INFO - Ignoring indexes: https://pypi.python.org/simple
11:39:09 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
11:39:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
11:39:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
11:39:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
11:39:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
11:39:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
11:39:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
11:39:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
11:39:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
11:39:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
11:39:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
11:39:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
11:39:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
11:39:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
11:39:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
11:39:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
11:39:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
11:39:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
11:39:12 INFO - Running setup.py install for manifestparser: started
11:39:12 INFO - Running setup.py install for manifestparser: finished with status 'done'
11:39:12 INFO - Running setup.py install for mozcrash: started
11:39:12 INFO - Running setup.py install for mozcrash: finished with status 'done'
11:39:12 INFO - Running setup.py install for mozdebug: started
11:39:12 INFO - Running setup.py install for mozdebug: finished with status 'done'
11:39:12 INFO - Running setup.py install for mozdevice: started
11:39:13 INFO - Running setup.py install for mozdevice: finished with status 'done'
11:39:13 INFO - Running setup.py install for mozfile: started
11:39:13 INFO - Running setup.py install for mozfile: finished with status 'done'
11:39:13 INFO - Running setup.py install for mozhttpd: started
11:39:13 INFO - Running setup.py install for mozhttpd: finished with status 'done'
11:39:13 INFO - Running setup.py install for mozinfo: started
11:39:13 INFO - Running setup.py install for mozinfo: finished with status 'done'
11:39:13 INFO - Running setup.py install for mozInstall: started
11:39:14 INFO - Running setup.py install for mozInstall: finished with status 'done'
11:39:14 INFO - Running setup.py install for mozleak: started
11:39:14 INFO - Running setup.py install for mozleak: finished with status 'done'
11:39:14 INFO - Running setup.py install for mozlog: started
11:39:14 INFO - Running setup.py install for mozlog: finished with status 'done'
11:39:14 INFO - Running setup.py install for moznetwork: started
11:39:14 INFO - Running setup.py install for moznetwork: finished with status 'done'
11:39:14 INFO - Running setup.py install for mozprocess: started
11:39:14 INFO - Running setup.py install for mozprocess: finished with status 'done'
11:39:14 INFO - Running setup.py install for mozprofile: started
11:39:15 INFO - Running setup.py install for mozprofile: finished with status 'done'
11:39:15 INFO - Running setup.py install for mozrunner: started
11:39:15 INFO - Running setup.py install for mozrunner: finished with status 'done'
11:39:15 INFO - Running setup.py install for mozscreenshot: started
11:39:15 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
11:39:15 INFO - Running setup.py install for moztest: started
11:39:15 INFO - Running setup.py install for moztest: finished with status 'done'
11:39:15 INFO - Running setup.py install for mozversion: started
11:39:16 INFO - Running setup.py install for mozversion: finished with status 'done'
11:39:16 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.14 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
11:39:16 INFO - Return code: 0
11:39:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:39:16 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 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:39:16 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
11:39:16 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
11:39:16 INFO - Using env: {'DISPLAY': ':0',
11:39:16 INFO - 'HOME': '/home/cltbld',
11:39:16 INFO - 'LANG': 'en_US.UTF-8',
11:39:16 INFO - 'LANGUAGE': 'en_US:en',
11:39:16 INFO - 'LOGNAME': 'cltbld',
11:39:16 INFO - 'MAIL': '/var/mail/cltbld',
11:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:16 INFO - 'MOZ_NO_REMOTE': '1',
11:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:16 INFO - 'NO_EM_RESTART': '1',
11:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:16 INFO - 'PWD': '/builds/slave/test',
11:39:16 INFO - 'SHELL': '/bin/bash',
11:39:16 INFO - 'SHLVL': '1',
11:39:16 INFO - 'TERM': 'linux',
11:39:16 INFO - 'TMOUT': '86400',
11:39:16 INFO - 'USER': 'cltbld',
11:39:16 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:16 INFO - '_': '/tools/buildbot/bin/python'}
11:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple
11:39:16 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
11:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
11:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
11:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
11:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
11:39:17 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))
11:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
11:39:17 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))
11:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
11:39:17 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))
11:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
11:39:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
11:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
11:39:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
11:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
11:39:18 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))
11:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
11:39:18 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))
11:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
11:39:18 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))
11:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
11:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
11:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
11:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
11:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
11:39:19 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))
11:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
11:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.14 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))
11:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
11:39:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
11:39:20 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
11:39:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
11:39:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
11:39:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
11:39:20 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
11:39:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
11:39:21 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))
11:39:21 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))
11:39:21 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))
11:39:21 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))
11:39:21 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))
11:39:21 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))
11:39:21 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
11:39:22 INFO - Installing collected packages: six, blessings
11:39:22 INFO - Successfully installed blessings-1.6 six-1.10.0
11:39:22 INFO - Return code: 0
11:39:22 INFO - Done creating virtualenv /builds/slave/test/build/venv.
11:39:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
11:39:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
11:39:23 INFO - Reading from file tmpfile_stdout
11:39:23 INFO - Reading from file tmpfile_stderr
11:39:23 INFO - Current package versions:
11:39:23 INFO - blessings == 1.6
11:39:23 INFO - blobuploader == 1.2.4
11:39:23 INFO - docopt == 0.6.1
11:39:23 INFO - functools32 == 3.2.3.post2
11:39:23 INFO - jsonschema == 2.5.1
11:39:23 INFO - manifestparser == 1.2
11:39:23 INFO - mozInstall == 1.14
11:39:23 INFO - mozcrash == 1.0
11:39:23 INFO - mozdebug == 0.1
11:39:23 INFO - mozdevice == 0.51
11:39:23 INFO - mozfile == 1.2
11:39:23 INFO - mozhttpd == 0.7
11:39:23 INFO - mozinfo == 0.10
11:39:23 INFO - mozleak == 0.1
11:39:23 INFO - mozlog == 3.5
11:39:23 INFO - moznetwork == 0.27
11:39:23 INFO - mozprocess == 0.25
11:39:23 INFO - mozprofile == 0.29
11:39:23 INFO - mozrunner == 6.14
11:39:23 INFO - mozscreenshot == 0.1
11:39:23 INFO - mozsystemmonitor == 0.3
11:39:23 INFO - moztest == 0.8
11:39:23 INFO - mozversion == 1.4
11:39:23 INFO - psutil == 3.1.1
11:39:23 INFO - requests == 1.2.3
11:39:23 INFO - six == 1.10.0
11:39:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv
11:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
11:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:39:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
11:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
11:39:23 INFO - Using env: {'DISPLAY': ':0',
11:39:23 INFO - 'HOME': '/home/cltbld',
11:39:23 INFO - 'LANG': 'en_US.UTF-8',
11:39:23 INFO - 'LANGUAGE': 'en_US:en',
11:39:23 INFO - 'LOGNAME': 'cltbld',
11:39:23 INFO - 'MAIL': '/var/mail/cltbld',
11:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:23 INFO - 'MOZ_NO_REMOTE': '1',
11:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:23 INFO - 'NO_EM_RESTART': '1',
11:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:23 INFO - 'PWD': '/builds/slave/test',
11:39:23 INFO - 'SHELL': '/bin/bash',
11:39:23 INFO - 'SHLVL': '1',
11:39:23 INFO - 'TERM': 'linux',
11:39:23 INFO - 'TMOUT': '86400',
11:39:23 INFO - 'USER': 'cltbld',
11:39:23 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:23 INFO - '_': '/tools/buildbot/bin/python'}
11:39:23 INFO - Ignoring indexes: https://pypi.python.org/simple
11:39:23 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))
11:39:23 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))
11:39:23 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))
11:39:23 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))
11:39:23 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))
11:39:23 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))
11:39:23 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))
11:39:23 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))
11:39:23 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))
11:39:23 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
11:39:24 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:39:25 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
11:39:25 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))
11:39:25 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))
11:39:25 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))
11:39:25 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))
11:39:26 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
11:39:26 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:39:27 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
11:39:27 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:39:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
11:39:29 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
11:39:30 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
11:39:30 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
11:39:30 INFO - Found existing installation: requests 1.2.3
11:39:30 INFO - Uninstalling requests-1.2.3:
11:39:30 INFO - Successfully uninstalled requests-1.2.3
11:39:30 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
11:39:30 INFO - Return code: 0
11:39:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
11:39: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')]}
11:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
11:39: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')]}
11:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
11:39:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16c3e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1726990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x184e250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057', '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
11:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
11:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
11:39:30 INFO - Using env: {'DISPLAY': ':0',
11:39:30 INFO - 'HOME': '/home/cltbld',
11:39:30 INFO - 'LANG': 'en_US.UTF-8',
11:39:30 INFO - 'LANGUAGE': 'en_US:en',
11:39:30 INFO - 'LOGNAME': 'cltbld',
11:39:30 INFO - 'MAIL': '/var/mail/cltbld',
11:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:30 INFO - 'MOZ_NO_REMOTE': '1',
11:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:30 INFO - 'NO_EM_RESTART': '1',
11:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:30 INFO - 'PWD': '/builds/slave/test',
11:39:30 INFO - 'SHELL': '/bin/bash',
11:39:30 INFO - 'SHLVL': '1',
11:39:30 INFO - 'TERM': 'linux',
11:39:30 INFO - 'TMOUT': '86400',
11:39:30 INFO - 'USER': 'cltbld',
11:39:30 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:30 INFO - '_': '/tools/buildbot/bin/python'}
11:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple
11:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
11:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
11:39:31 INFO - Return code: 0
11:39:31 INFO - Running post-action listener: _resource_record_post_action
11:39:31 INFO - Running post-action listener: _start_resource_monitoring
11:39:31 INFO - Starting resource monitoring.
11:39:31 INFO - [mozharness: 2017-11-30 19:39:31.084508Z] Finished create-virtualenv step (success)
11:39:31 INFO - [mozharness: 2017-11-30 19:39:31.084982Z] Running install step.
11:39:31 INFO - Running pre-action listener: _resource_record_pre_action
11:39:31 INFO - Running main action method: install
11:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
11:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
11:39:31 INFO - Reading from file tmpfile_stdout
11:39:31 INFO - Reading from file tmpfile_stderr
11:39:31 INFO - Detecting whether we're running mozinstall >=1.0...
11:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
11:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
11:39:31 INFO - Reading from file tmpfile_stdout
11:39:31 INFO - Output received:
11:39:31 INFO - Usage: mozinstall [options] installer
11:39:31 INFO - Options:
11:39:31 INFO - -h, --help show this help message and exit
11:39:31 INFO - -d DEST, --destination=DEST
11:39:31 INFO - Directory to install application into. [default:
11:39:31 INFO - "/builds/slave/test"]
11:39:31 INFO - --app=APP Application being installed. [default: firefox]
11:39:31 INFO - mkdir: /builds/slave/test/build/application
11:39:31 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']
11:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
11:39:50 INFO - Reading from file tmpfile_stdout
11:39:50 INFO - Output received:
11:39:50 INFO - /builds/slave/test/build/application/firefox/firefox
11:39:50 INFO - Running post-action listener: _resource_record_post_action
11:39:50 INFO - [mozharness: 2017-11-30 19:39:50.129248Z] Finished install step (success)
11:39:50 INFO - [mozharness: 2017-11-30 19:39:50.129572Z] Running setup-mitmproxy step.
11:39:50 INFO - Running pre-action listener: _resource_record_pre_action
11:39:50 INFO - Running main action method: setup_mitmproxy
11:39:50 INFO - Skipping: mitmproxy is not required
11:39:50 INFO - Running post-action listener: _resource_record_post_action
11:39:50 INFO - [mozharness: 2017-11-30 19:39:50.131196Z] Finished setup-mitmproxy step (success)
11:39:50 INFO - [mozharness: 2017-11-30 19:39:50.131500Z] Running run-tests step.
11:39:50 INFO - Running pre-action listener: _resource_record_pre_action
11:39:50 INFO - Running pre-action listener: _set_gcov_prefix
11:39:50 INFO - Running main action method: run_tests
11:39:50 WARNING - Try message not found.
11:39:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
11:39:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
11:39:50 INFO - Python 2.7.3
11:39:50 INFO - Return code: 0
11:39:50 INFO - grabbing minidump binary from tooltool
11:39: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')]}
11:39:50 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 0x184b430>, '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 0x184c330>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x184c7c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
11:39:50 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
11:39:50 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
11:39:50 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
11:39:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
11:39:50 INFO - Return code: 0
11:39:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
11:39:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
11:39:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
11:39:50 INFO - ENV: RUST_BACKTRACE is now full
11:39:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
11:39:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
11:39:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
11:39:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
11:39:50 INFO - Using env: {'DISPLAY': ':0',
11:39:50 INFO - 'HOME': '/home/cltbld',
11:39:50 INFO - 'LANG': 'en_US.UTF-8',
11:39:50 INFO - 'LANGUAGE': 'en_US:en',
11:39:50 INFO - 'LOGNAME': 'cltbld',
11:39:50 INFO - 'MAIL': '/var/mail/cltbld',
11:39:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:39:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:39:50 INFO - 'MOZ_NO_REMOTE': '1',
11:39:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:39:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:39:50 INFO - 'NO_EM_RESTART': '1',
11:39:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:39:50 INFO - 'PWD': '/builds/slave/test',
11:39:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:39:50 INFO - 'RUST_BACKTRACE': 'full',
11:39:50 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:39:50 INFO - 'SHELL': '/bin/bash',
11:39:50 INFO - 'SHLVL': '1',
11:39:50 INFO - 'TERM': 'linux',
11:39:50 INFO - 'TMOUT': '86400',
11:39:50 INFO - 'USER': 'cltbld',
11:39:50 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:39:50 INFO - '_': '/tools/buildbot/bin/python'}
11:39:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
11:39:50 INFO - mozversion application_buildid: 20171130184939
11:39:50 INFO - mozversion application_changeset: a3a5f13265750c9bd6589cc61ebbffceeea476a4
11:39:50 INFO - mozversion application_display_name: Nightly
11:39:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
11:39:50 INFO - mozversion application_name: Firefox
11:39:50 INFO - mozversion application_remotingname: firefox
11:39:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
11:39:50 INFO - mozversion application_vendor: Mozilla
11:39:50 INFO - mozversion application_version: 59.0a1
11:39:50 INFO - mozversion platform_buildid: 20171130184939
11:39:50 INFO - mozversion platform_changeset: a3a5f13265750c9bd6589cc61ebbffceeea476a4
11:39:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
11:39:50 INFO - mozversion platform_version: 59.0a1
11:39:50 INFO - using testdate: 1512070790
11:39:50 INFO - actual date: 1512070790
11:39:50 INFO - starting webserver on 'localhost:39025'
11:39:50 INFO - SUITE-START | Running 3 tests
11:39:50 INFO - TEST-START | basic_compositor_video
11:39:50 INFO - Initialising browser for basic_compositor_video test...
11:39:50 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
11:39:50 INFO - Installing Add-ons
11:39:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39025/getInfo.html -profile /tmp/tmpOSz557/profile
11:39:50 INFO - TEST-INFO | started process 17919 (/builds/slave/test/build/application/firefox/firefox http://localhost:39025/getInfo.html)
11:40:02 INFO - TEST-INFO | 17919: exit 0
11:40:02 INFO - Browser initialized.
11:40:02 INFO - Running cycle 1/1 for basic_compositor_video test...
11:40:02 INFO - Using env: {'DISPLAY': ':0',
11:40:02 INFO - 'HOME': '/home/cltbld',
11:40:02 INFO - 'JSGC_DISABLE_POISONING': '1',
11:40:02 INFO - 'LANG': 'en_US.UTF-8',
11:40:02 INFO - 'LANGUAGE': 'en_US:en',
11:40:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
11:40:02 INFO - 'LOGNAME': 'cltbld',
11:40:02 INFO - 'MAIL': '/var/mail/cltbld',
11:40:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:40:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:40:02 INFO - 'MOZ_CRASHREPORTER': '1',
11:40:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:40:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
11:40:02 INFO - 'MOZ_NO_REMOTE': '1',
11:40:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:40:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:40:02 INFO - 'NO_EM_RESTART': '1',
11:40:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:40:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:40:02 INFO - 'PWD': '/builds/slave/test',
11:40:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:40:02 INFO - 'RUST_BACKTRACE': 'full',
11:40:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:40:02 INFO - 'SHELL': '/bin/bash',
11:40:02 INFO - 'SHLVL': '1',
11:40:02 INFO - 'TERM': 'linux',
11:40:02 INFO - 'TMOUT': '86400',
11:40:02 INFO - 'USER': 'cltbld',
11:40:02 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:40:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:40:02 INFO - '_': '/tools/buildbot/bin/python'}
11:40:02 INFO - TEST-INFO | started process 18215 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOSz557/profile)
11:40:03 INFO - PID 18215 |
11:40:03 INFO - PID 18215 | (/builds/slave/test/build/application/firefox/firefox:18266): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:40:03 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855786516853934 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682151793160969 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7387630359212054 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.75608867427568 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 3.199456289978678 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.083530612244899 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.5220305164319234 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.408168654173767 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.850107212475633 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.665892351274791 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 9.29136222910217 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 10.000850000000009 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.629457364341091 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.17773743016761 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.662510548523205 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835828402366891 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49724137931034 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812837837837838 ms/frame
11:40:49 INFO - PID 18215 |
11:40:49 INFO - PID 18215 | Cycle 1(1): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:41:35 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800783874580068 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6757202680067005 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8013535414165662 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7784577777777781 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.220042194092826 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.057971602434077 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.6814907975460134 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5532682060390726 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.792162162162162 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.45238419618529 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 9.617644230769237 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.664879227053127 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.456259541984736 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.053839779005498 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606092436974789 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.424472049689419 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.675933852140064 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.301016949152535 ms/frame
11:41:35 INFO - PID 18215 |
11:41:35 INFO - PID 18215 | Cycle 1(2): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:42:21 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6865964024732996 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6727257525083612 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7397449275362316 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.749785651793526 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.143819060773481 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.193909853249478 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.690768757687576 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.604873873873877 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650574387947272 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683565340909095 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 10.45588850174216 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.303279069767433 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.630930232558136 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.238820224719113 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.451099585062225 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053192771084348 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452595419847334 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.930300546448063 ms/frame
11:42:21 INFO - PID 18215 |
11:42:21 INFO - PID 18215 | Cycle 1(3): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:43:07 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6837514029180696 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.68280487804878 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7662036492054147 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.747414847161573 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 3.923006535947713 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1495643153526975 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.628917775090689 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.559724199288254 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.70506653992395 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.496414835164835 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 10.309845360824745 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.436462264150938 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.538865384615383 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.111805555555556 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.713305084745778 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.908273809523788 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.721523437499997 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.236966292134833 ms/frame
11:43:07 INFO - PID 18215 |
11:43:07 INFO - PID 18215 | Cycle 1(4): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:43:53 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6854747191011237 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669845575959933 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7830808080808083 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7607746478873245 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.049136302294197 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.185554393305439 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.538573113207546 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.466750433275567 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.618988764044945 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.7647118155619586 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 9.86975328947368 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.527619047619044 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.542153846153829 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.99222527472531 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605672268907568 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97757485029943 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583918918918894 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.115083333333354 ms/frame
11:43:53 INFO - PID 18215 |
11:43:53 INFO - PID 18215 | Cycle 1(5): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:44:39 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6856011235955057 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687698081734776 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7754556213017754 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7335615251299832 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.156295013850414 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9077050781250016 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.4452468427095306 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4134982935153606 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650819209039547 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635295774647884 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 9.523984126984129 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.05178733031674 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.722578125000013 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.11677777777776 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660063291139231 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.122181818181796 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.861818181818157 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.50063218390805 ms/frame
11:44:39 INFO - PID 18215 |
11:44:39 INFO - PID 18215 | Cycle 1(6): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:45:26 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6846996069623805 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6703923205342237 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.771481700118064 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7291832324978391 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.14417817679558 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.142277432712214 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.6236654589371966 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.643023679417123 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.771423076923075 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5870111731843615 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 10.13554054054055 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.902772277227708 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.451812977099223 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.813810810810812 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450497925311202 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052620481927715 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630232558139529 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.23955056179775 ms/frame
11:45:26 INFO - PID 18215 |
11:45:26 INFO - PID 18215 | Cycle 1(7): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:46:12 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6820123318385651 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6704424040066783 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7858065476190481 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7812199465716825 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.161643550624134 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.098473360655738 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.546483451536644 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.478513043478262 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683380681818183 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.558749999999994 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 10.170983050847454 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.262268518518525 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.720078125000015 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.178603351955273 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.661160337552742 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.12193939393941 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.76676470588233 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.240505617977556 ms/frame
11:46:12 INFO - PID 18215 |
11:46:12 INFO - PID 18215 | Cycle 1(8): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:46:58 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819618834080718 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.666182348043297 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7515528312901343 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7217771084337357 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.219957805907172 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.228636363636364 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.6064423076923093 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5844534050179213 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.860605468750002 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.61817415730337 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 9.74087662337663 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.710606796116497 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.539019230769233 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.93109289617486 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.770468085106405 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.122454545454511 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.812066929133856 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.561329479768789 ms/frame
11:46:58 INFO - PID 18215 |
11:46:58 INFO - PID 18215 | Cycle 1(9): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:47:44 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6830931015143018 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.673035117056856 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7741986989946779 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7406962576153173 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.25590070921986 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.048795546558703 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.7005055487053027 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.643743169398902 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.726498091603049 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618735955056181 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 10.27806506849315 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.216866359446994 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.5849420849421 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.115916666666655 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605756302521002 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.196006097560955 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.906230158730141 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111194444444441 ms/frame
11:47:44 INFO - PID 18215 |
11:47:44 INFO - PID 18215 | Cycle 1(10): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:48:30 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.683827160493827 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710985797827906 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7986420863309354 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7504418197725284 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.185690376569039 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.100256147540982 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.615018072289159 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.522112676056334 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7707307692307666 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.497733516483525 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 10.309604810996555 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.220506912442406 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.497068965517244 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.815324324324319 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.769808510638324 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.423074534161477 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.765784313725495 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.114111111111116 ms/frame
11:48:30 INFO - PID 18215 |
11:48:30 INFO - PID 18215 | Cycle 1(11): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:49:16 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_startup = 1.687017987633502 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710651629072684 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.774361324659965 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.753742331288344 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_startup = 4.214143258426968 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.09066462167689 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_startup = 3.554739336492892 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4378780068728543 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.825796116504854 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.496126373626374 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_startup = 9.837147540983601 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.480p.60fps.webm_scale_2_inclip = 9.66364734299517 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.539788461538452 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.934918032786905 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.879055793991416 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.12260606060608 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586814671814674 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.933879781420773 ms/frame
11:49:16 INFO - PID 18215 |
11:49:16 INFO - PID 18215 | Cycle 1(12): loaded http://localhost:39025/tests/video/video_playback.html (next: http://localhost:39025/tests/video/video_playback.html)
11:49:17 INFO - PID 18215 | __start_tp_report
11:49:17 INFO - PID 18215 | _x_x_mozilla_page_load
11:49:17 INFO - PID 18215 | _x_x_mozilla_page_load_details
11:49:17 INFO - PID 18215 | |i|pagename|runs|
11:49:17 INFO - PID 18215 | |0;240p.120fps.mp4_scale_1_startup;1.6855786516853934;1.6800783874580068;1.6865964024732996;1.6837514029180696;1.6854747191011237;1.6856011235955057;1.6846996069623805;1.6820123318385651;1.6819618834080718;1.6830931015143018;1.683827160493827;1.687017987633502
11:49:17 INFO - PID 18215 | |1;240p.120fps.mp4_scale_1_inclip;1.6682151793160969;1.6757202680067005;1.6727257525083612;1.68280487804878;1.669845575959933;1.6687698081734776;1.6703923205342237;1.6704424040066783;1.666182348043297;1.673035117056856;1.6710985797827906;1.6710651629072684
11:49:17 INFO - PID 18215 | |2;240p.120fps.mp4_scale_1.1_startup;1.7387630359212054;1.8013535414165662;1.7397449275362316;1.7662036492054147;1.7830808080808083;1.7754556213017754;1.771481700118064;1.7858065476190481;1.7515528312901343;1.7741986989946779;1.7986420863309354;1.774361324659965
11:49:17 INFO - PID 18215 | |3;240p.120fps.mp4_scale_1.1_inclip;1.75608867427568;1.7784577777777781;1.749785651793526;1.747414847161573;1.7607746478873245;1.7335615251299832;1.7291832324978391;1.7812199465716825;1.7217771084337357;1.7406962576153173;1.7504418197725284;1.753742331288344
11:49:17 INFO - PID 18215 | |4;240p.120fps.mp4_scale_2_startup;3.199456289978678;4.220042194092826;4.143819060773481;3.923006535947713;4.049136302294197;4.156295013850414;4.14417817679558;4.161643550624134;4.219957805907172;4.25590070921986;4.185690376569039;4.214143258426968
11:49:17 INFO - PID 18215 | |5;240p.120fps.mp4_scale_2_inclip;4.083530612244899;4.057971602434077;4.193909853249478;4.1495643153526975;4.185554393305439;3.9077050781250016;4.142277432712214;4.098473360655738;4.228636363636364;4.048795546558703;4.100256147540982;4.09066462167689
11:49:17 INFO - PID 18215 | |6;480p.60fps.webm_scale_1_startup;3.5220305164319234;3.6814907975460134;3.690768757687576;3.628917775090689;3.538573113207546;3.4452468427095306;3.6236654589371966;3.546483451536644;3.6064423076923093;3.7005055487053027;3.615018072289159;3.554739336492892
11:49:17 INFO - PID 18215 | |7;480p.60fps.webm_scale_1_inclip;3.408168654173767;3.5532682060390726;3.604873873873877;3.559724199288254;3.466750433275567;3.4134982935153606;3.643023679417123;3.478513043478262;3.5844534050179213;3.643743169398902;3.522112676056334;3.4378780068728543
11:49:17 INFO - PID 18215 | |8;480p.60fps.webm_scale_1.1_startup;5.850107212475633;5.792162162162162;5.650574387947272;5.70506653992395;5.618988764044945;5.650819209039547;5.771423076923075;5.683380681818183;5.860605468750002;5.726498091603049;5.7707307692307666;5.825796116504854
11:49:17 INFO - PID 18215 | |9;480p.60fps.webm_scale_1.1_inclip;5.665892351274791;5.45238419618529;5.683565340909095;5.496414835164835;5.7647118155619586;5.635295774647884;5.5870111731843615;5.558749999999994;5.61817415730337;5.618735955056181;5.497733516483525;5.496126373626374
11:49:17 INFO - PID 18215 | |10;480p.60fps.webm_scale_2_startup;9.29136222910217;9.617644230769237;10.45588850174216;10.309845360824745;9.86975328947368;9.523984126984129;10.13554054054055;10.170983050847454;9.74087662337663;10.27806506849315;10.309604810996555;9.837147540983601
11:49:17 INFO - PID 18215 | |11;480p.60fps.webm_scale_2_inclip;10.000850000000009;9.664879227053127;9.303279069767433;9.436462264150938;9.527619047619044;9.05178733031674;9.902772277227708;9.262268518518525;9.710606796116497;9.216866359446994;9.220506912442406;9.66364734299517
11:49:17 INFO - PID 18215 | |12;1080p.60fps.mp4_scale_1_startup;11.629457364341091;11.456259541984736;11.630930232558136;11.538865384615383;11.542153846153829;11.722578125000013;11.451812977099223;11.720078125000015;11.539019230769233;11.5849420849421;11.497068965517244;11.539788461538452
11:49:17 INFO - PID 18215 | |13;1080p.60fps.mp4_scale_1_inclip;11.17773743016761;11.053839779005498;11.238820224719113;11.111805555555556;10.99222527472531;11.11677777777776;10.813810810810812;11.178603351955273;10.93109289617486;11.115916666666655;10.815324324324319;10.934918032786905
11:49:17 INFO - PID 18215 | |14;1080p.60fps.mp4_scale_1.1_startup;12.662510548523205;12.606092436974789;12.451099585062225;12.713305084745778;12.605672268907568;12.660063291139231;12.450497925311202;12.661160337552742;12.770468085106405;12.605756302521002;12.769808510638324;12.879055793991416
11:49:17 INFO - PID 18215 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.835828402366891;12.424472049689419;12.053192771084348;11.908273809523788;11.97757485029943;12.122181818181796;12.052620481927715;12.12193939393941;12.122454545454511;12.196006097560955;12.423074534161477;12.12260606060608
11:49:17 INFO - PID 18215 | |16;1080p.60fps.mp4_scale_2_startup;11.49724137931034;11.675933852140064;11.452595419847334;11.721523437499997;11.583918918918894;11.861818181818157;11.630232558139529;11.76676470588233;11.812066929133856;11.906230158730141;11.765784313725495;11.586814671814674
11:49:17 INFO - PID 18215 | |17;1080p.60fps.mp4_scale_2_inclip;10.812837837837838;11.301016949152535;10.930300546448063;11.236966292134833;11.115083333333354;11.50063218390805;11.23955056179775;11.240505617977556;11.561329479768789;11.111194444444441;11.114111111111116;10.933879781420773
11:49:17 INFO - PID 18215 | __end_tp_report
11:49:17 INFO - PID 18215 | __start_cc_report
11:49:17 INFO - PID 18215 | _x_x_mozilla_cycle_collect,626
11:49:17 INFO - PID 18215 | __end_cc_report
11:49:17 INFO - PID 18215 | __startTimestamp1512071357230__endTimestamp
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | ------- Summary: start -------
11:49:17 INFO - PID 18215 | Number of tests: 18
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.69 stddev:0.00 (0.1%) stddev-sans-first:0.00
11:49:17 INFO - PID 18215 | 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
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.3%) stddev-sans-first:0.00
11:49:17 INFO - PID 18215 | 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
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.77 Median:1.77 stddev:0.02 (1.1%) stddev-sans-first:0.02
11:49:17 INFO - PID 18215 | Values: 1.7 1.8 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.75 stddev:0.02 (1.0%) stddev-sans-first:0.02
11:49:17 INFO - PID 18215 | Values: 1.8 1.8 1.7 1.7 1.8 1.7 1.7 1.8 1.7 1.7 1.8 1.8
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.07 Median:4.17 stddev:0.29 (6.9%) stddev-sans-first:0.09
11:49:17 INFO - PID 18215 | Values: 3.2 4.2 4.1 3.9 4.0 4.2 4.1 4.2 4.2 4.3 4.2 4.2
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.11 Median:4.12 stddev:0.08 (2.0%) stddev-sans-first:0.09
11:49:17 INFO - PID 18215 | Values: 4.1 4.1 4.2 4.1 4.2 3.9 4.1 4.1 4.2 4.0 4.1 4.1
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.60 Median:3.62 stddev:0.08 (2.1%) stddev-sans-first:0.08
11:49:17 INFO - PID 18215 | Values: 3.5 3.7 3.7 3.6 3.5 3.4 3.6 3.5 3.6 3.7 3.6 3.6
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.53 Median:3.56 stddev:0.08 (2.4%) stddev-sans-first:0.08
11:49:17 INFO - PID 18215 | Values: 3.4 3.6 3.6 3.6 3.5 3.4 3.6 3.5 3.6 3.6 3.5 3.4
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.74 Median:5.77 stddev:0.08 (1.4%) stddev-sans-first:0.08
11:49:17 INFO - PID 18215 | Values: 5.9 5.8 5.7 5.7 5.6 5.7 5.8 5.7 5.9 5.7 5.8 5.8
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.59 Median:5.62 stddev:0.09 (1.7%) stddev-sans-first:0.09
11:49:17 INFO - PID 18215 | Values: 5.7 5.5 5.7 5.5 5.8 5.6 5.6 5.6 5.6 5.6 5.5 5.5
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.96 Median:9.41 stddev:0.37 (3.9%) stddev-sans-first:0.32
11:49:17 INFO - PID 18215 | Values: 9.3 9.6 10.5 10.3 9.9 9.5 10.1 10.2 9.7 10.3 10.3 9.8
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.50 Median:9.48 stddev:0.30 (3.1%) stddev-sans-first:0.26
11:49:17 INFO - PID 18215 | Values: 10.0 9.7 9.3 9.4 9.5 9.1 9.9 9.3 9.7 9.2 9.2 9.7
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.57 Median:11.56 stddev:0.09 (0.8%) stddev-sans-first:0.09
11:49:17 INFO - PID 18215 | Values: 11.6 11.5 11.6 11.5 11.5 11.7 11.5 11.7 11.5 11.6 11.5 11.5
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:11.04 Median:11.11 stddev:0.14 (1.3%) stddev-sans-first:0.14
11:49:17 INFO - PID 18215 | Values: 11.2 11.1 11.2 11.1 11.0 11.1 10.8 11.2 10.9 11.1 10.8 10.9
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.65 Median:12.66 stddev:0.12 (1.0%) stddev-sans-first:0.13
11:49:17 INFO - PID 18215 | Values: 12.7 12.6 12.5 12.7 12.6 12.7 12.5 12.7 12.8 12.6 12.8 12.9
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.11 Median:12.12 stddev:0.18 (1.5%) stddev-sans-first:0.16
11:49:17 INFO - PID 18215 | Values: 11.8 12.4 12.1 11.9 12.0 12.1 12.1 12.1 12.1 12.2 12.4 12.1
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.69 Median:11.74 stddev:0.14 (1.2%) stddev-sans-first:0.14
11:49:17 INFO - PID 18215 | Values: 11.5 11.7 11.5 11.7 11.6 11.9 11.6 11.8 11.8 11.9 11.8 11.6
11:49:17 INFO - PID 18215 |
11:49:17 INFO - PID 18215 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.17 Median:11.24 stddev:0.22 (2.0%) stddev-sans-first:0.20
11:49:17 INFO - PID 18215 | Values: 10.8 11.3 10.9 11.2 11.1 11.5 11.2 11.2 11.6 11.1 11.1 10.9
11:49:17 INFO - PID 18215 | -------- Summary: end --------
11:49:17 INFO - PID 18215 |
11:49:22 INFO - TEST-INFO | 18215: exit 0
11:49:22 INFO - TEST-OK | basic_compositor_video | took 572229ms
11:49:22 INFO - TEST-START | glvideo
11:49:22 INFO - Initialising browser for glvideo test...
11:49:22 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
11:49:22 INFO - Installing Add-ons
11:49:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39025/getInfo.html -profile /tmp/tmpYG05Zq/profile
11:49:22 INFO - TEST-INFO | started process 19468 (/builds/slave/test/build/application/firefox/firefox http://localhost:39025/getInfo.html)
11:49:34 INFO - TEST-INFO | 19468: exit 0
11:49:34 INFO - Browser initialized.
11:49:34 INFO - Running cycle 1/1 for glvideo test...
11:49:34 INFO - Using env: {'DISPLAY': ':0',
11:49:34 INFO - 'HOME': '/home/cltbld',
11:49:34 INFO - 'JSGC_DISABLE_POISONING': '1',
11:49:34 INFO - 'LANG': 'en_US.UTF-8',
11:49:34 INFO - 'LANGUAGE': 'en_US:en',
11:49:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
11:49:34 INFO - 'LOGNAME': 'cltbld',
11:49:34 INFO - 'MAIL': '/var/mail/cltbld',
11:49:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:49:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:49:34 INFO - 'MOZ_CRASHREPORTER': '1',
11:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:49:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
11:49:34 INFO - 'MOZ_NO_REMOTE': '1',
11:49:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:49:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:49:34 INFO - 'NO_EM_RESTART': '1',
11:49:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:49:34 INFO - 'PWD': '/builds/slave/test',
11:49:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:49:34 INFO - 'RUST_BACKTRACE': 'full',
11:49:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:49:34 INFO - 'SHELL': '/bin/bash',
11:49:34 INFO - 'SHLVL': '1',
11:49:34 INFO - 'TERM': 'linux',
11:49:34 INFO - 'TMOUT': '86400',
11:49:34 INFO - 'USER': 'cltbld',
11:49:34 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:49:34 INFO - '_': '/tools/buildbot/bin/python'}
11:49:34 INFO - TEST-INFO | started process 19800 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYG05Zq/profile)
11:49:35 INFO - PID 19800 |
11:49:35 INFO - PID 19800 | (/builds/slave/test/build/application/firefox/firefox:19851): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:49:35 INFO - PID 19800 |
11:49:36 INFO - PID 19800 | [talos glvideo result] Mean tick time across 100 ticks: 6.784350000000001 ms
11:49:36 INFO - PID 19800 | Cycle 1(1): loaded http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html)
11:49:38 INFO - PID 19800 | [talos glvideo result] Mean tick time across 100 ticks: 6.709700000000001 ms
11:49:38 INFO - PID 19800 | Cycle 1(2): loaded http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html)
11:49:39 INFO - PID 19800 | [talos glvideo result] Mean tick time across 100 ticks: 6.503150000000001 ms
11:49:39 INFO - PID 19800 | Cycle 1(3): loaded http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html)
11:49:40 INFO - PID 19800 | [talos glvideo result] Mean tick time across 100 ticks: 6.5375 ms
11:49:40 INFO - PID 19800 | Cycle 1(4): loaded http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html)
11:49:42 INFO - PID 19800 | [talos glvideo result] Mean tick time across 100 ticks: 6.5818 ms
11:49:42 INFO - PID 19800 | Cycle 1(5): loaded http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39025/tests/webgl/benchmarks/video/video_upload.html)
11:49:42 INFO - PID 19800 | __start_tp_report
11:49:42 INFO - PID 19800 | _x_x_mozilla_page_load
11:49:42 INFO - PID 19800 | _x_x_mozilla_page_load_details
11:49:42 INFO - PID 19800 | |i|pagename|runs|
11:49:42 INFO - PID 19800 | |0;Mean tick time across 100 ticks: ;6.784350000000001;6.709700000000001;6.503150000000001;6.5375;6.5818
11:49:42 INFO - PID 19800 | __end_tp_report
11:49:42 INFO - PID 19800 | __start_cc_report
11:49:42 INFO - PID 19800 | _x_x_mozilla_cycle_collect,286
11:49:42 INFO - PID 19800 | __end_cc_report
11:49:42 INFO - PID 19800 | __startTimestamp1512071382394__endTimestamp
11:49:42 INFO - PID 19800 |
11:49:42 INFO - PID 19800 | ------- Summary: start -------
11:49:42 INFO - PID 19800 | Number of tests: 1
11:49:42 INFO - PID 19800 |
11:49:42 INFO - PID 19800 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.62 Median:6.58 stddev:0.12 (1.8%) stddev-sans-first:0.09
11:49:42 INFO - PID 19800 | Values: 6.8 6.7 6.5 6.5 6.6
11:49:42 INFO - PID 19800 | -------- Summary: end --------
11:49:42 INFO - PID 19800 |
11:49:42 INFO - PID 19800 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
11:49:48 INFO - TEST-INFO | 19800: exit 0
11:49:48 INFO - TEST-OK | glvideo | took 25315ms
11:49:48 INFO - TEST-START | displaylist_mutate
11:49:48 INFO - Initialising browser for displaylist_mutate test...
11:49:48 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
11:49:48 INFO - Installing Add-ons
11:49:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39025/getInfo.html -profile /tmp/tmpHRfRGI/profile
11:49:48 INFO - TEST-INFO | started process 20027 (/builds/slave/test/build/application/firefox/firefox http://localhost:39025/getInfo.html)
11:49:59 INFO - TEST-INFO | 20027: exit 0
11:49:59 INFO - Browser initialized.
11:49:59 INFO - Running cycle 1/1 for displaylist_mutate test...
11:49:59 INFO - Using env: {'DISPLAY': ':0',
11:49:59 INFO - 'HOME': '/home/cltbld',
11:49:59 INFO - 'JSGC_DISABLE_POISONING': '1',
11:49:59 INFO - 'LANG': 'en_US.UTF-8',
11:49:59 INFO - 'LANGUAGE': 'en_US:en',
11:49:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
11:49:59 INFO - 'LOGNAME': 'cltbld',
11:49:59 INFO - 'MAIL': '/var/mail/cltbld',
11:49:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
11:49:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
11:49:59 INFO - 'MOZ_CRASHREPORTER': '1',
11:49:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
11:49:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
11:49:59 INFO - 'MOZ_NO_REMOTE': '1',
11:49:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
11:49:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
11:49:59 INFO - 'NO_EM_RESTART': '1',
11:49:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
11:49:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
11:49:59 INFO - 'PWD': '/builds/slave/test',
11:49:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
11:49:59 INFO - 'RUST_BACKTRACE': 'full',
11:49:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
11:49:59 INFO - 'SHELL': '/bin/bash',
11:49:59 INFO - 'SHLVL': '1',
11:49:59 INFO - 'TERM': 'linux',
11:49:59 INFO - 'TMOUT': '86400',
11:49:59 INFO - 'USER': 'cltbld',
11:49:59 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1512070273.598434-74646057',
11:49:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
11:49:59 INFO - '_': '/tools/buildbot/bin/python'}
11:49:59 INFO - TEST-INFO | started process 20341 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHRfRGI/profile)
11:50:00 INFO - PID 20341 |
11:50:00 INFO - PID 20341 | (/builds/slave/test/build/application/firefox/firefox:20392): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
11:50:00 INFO - PID 20341 |
11:50:06 INFO - PID 20341 | Cycle 1(1): loaded http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html)
11:50:12 INFO - PID 20341 | Cycle 1(2): loaded http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html)
11:50:19 INFO - PID 20341 | Cycle 1(3): loaded http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html)
11:50:25 INFO - PID 20341 | Cycle 1(4): loaded http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html)
11:50:25 INFO - PID 20341 | Waiting for idle-callback
11:50:31 INFO - PID 20341 | Cycle 1(5): loaded http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:39025/tests/layout/benchmarks/displaylist_mutate.html)
11:50:32 INFO - PID 20341 | __start_tp_report
11:50:32 INFO - PID 20341 | _x_x_mozilla_page_load
11:50:32 INFO - PID 20341 | _x_x_mozilla_page_load_details
11:50:32 INFO - PID 20341 | |i|pagename|runs|
11:50:32 INFO - PID 20341 | |0;/displaylist_mutate.html;5343.435;5326.88;5582.93;5596.695;5632.405000000001
11:50:32 INFO - PID 20341 | __end_tp_report
11:50:32 INFO - PID 20341 | __start_cc_report
11:50:32 INFO - PID 20341 | _x_x_mozilla_cycle_collect,266
11:50:32 INFO - PID 20341 | __end_cc_report
11:50:32 INFO - PID 20341 | __startTimestamp1512071432149__endTimestamp
11:50:32 INFO - PID 20341 |
11:50:32 INFO - PID 20341 | ------- Summary: start -------
11:50:32 INFO - PID 20341 | Number of tests: 1
11:50:32 INFO - PID 20341 |
11:50:32 INFO - PID 20341 | [#0] /displaylist_mutate.html Cycles:5 Average:5496.47 Median:5582.93 stddev:148.47 (2.7%) stddev-sans-first:140.12
11:50:32 INFO - PID 20341 | Values: 5343.4 5326.9 5582.9 5596.7 5632.4
11:50:32 INFO - PID 20341 | -------- Summary: end --------
11:50:32 INFO - PID 20341 |
11:50:32 INFO - PID 20341 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
11:50:37 INFO - TEST-INFO | 20341: exit 0
11:50:37 INFO - TEST-OK | displaylist_mutate | took 49742ms
11:50:37 INFO - SUITE-END | took 647s
11:50:38 INFO - Completed test suite (00:10:47)
11:50:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.6558017823971145, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6855786516853934, 1.6800783874580068, 1.6865964024732996, 1.6837514029180696, 1.6854747191011237, 1.6856011235955057, 1.6846996069623805, 1.6820123318385651, 1.6819618834080718, 1.6830931015143018, 1.683827160493827, 1.687017987633502], "value": 1.683827160493827, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6682151793160969, 1.6757202680067005, 1.6727257525083612, 1.68280487804878, 1.669845575959933, 1.6687698081734776, 1.6703923205342237, 1.6704424040066783, 1.666182348043297, 1.673035117056856, 1.6710985797827906, 1.6710651629072684], "value": 1.6710651629072684, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7387630359212054, 1.8013535414165662, 1.7397449275362316, 1.7662036492054147, 1.7830808080808083, 1.7754556213017754, 1.771481700118064, 1.7858065476190481, 1.7515528312901343, 1.7741986989946779, 1.7986420863309354, 1.774361324659965], "value": 1.774361324659965, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.75608867427568, 1.7784577777777781, 1.749785651793526, 1.747414847161573, 1.7607746478873245, 1.7335615251299832, 1.7291832324978391, 1.7812199465716825, 1.7217771084337357, 1.7406962576153173, 1.7504418197725284, 1.753742331288344], "value": 1.749785651793526, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.199456289978678, 4.220042194092826, 4.143819060773481, 3.923006535947713, 4.049136302294197, 4.156295013850414, 4.14417817679558, 4.161643550624134, 4.219957805907172, 4.25590070921986, 4.185690376569039, 4.214143258426968], "value": 4.161643550624134, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.083530612244899, 4.057971602434077, 4.193909853249478, 4.1495643153526975, 4.185554393305439, 3.9077050781250016, 4.142277432712214, 4.098473360655738, 4.228636363636364, 4.048795546558703, 4.100256147540982, 4.09066462167689], "value": 4.100256147540982, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5220305164319234, 3.6814907975460134, 3.690768757687576, 3.628917775090689, 3.538573113207546, 3.4452468427095306, 3.6236654589371966, 3.546483451536644, 3.6064423076923093, 3.7005055487053027, 3.615018072289159, 3.554739336492892], "value": 3.615018072289159, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.408168654173767, 3.5532682060390726, 3.604873873873877, 3.559724199288254, 3.466750433275567, 3.4134982935153606, 3.643023679417123, 3.478513043478262, 3.5844534050179213, 3.643743169398902, 3.522112676056334, 3.4378780068728543], "value": 3.5532682060390726, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.850107212475633, 5.792162162162162, 5.650574387947272, 5.70506653992395, 5.618988764044945, 5.650819209039547, 5.771423076923075, 5.683380681818183, 5.860605468750002, 5.726498091603049, 5.7707307692307666, 5.825796116504854], "value": 5.726498091603049, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.665892351274791, 5.45238419618529, 5.683565340909095, 5.496414835164835, 5.7647118155619586, 5.635295774647884, 5.5870111731843615, 5.558749999999994, 5.61817415730337, 5.618735955056181, 5.497733516483525, 5.496126373626374], "value": 5.5870111731843615, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.29136222910217, 9.617644230769237, 10.45588850174216, 10.309845360824745, 9.86975328947368, 9.523984126984129, 10.13554054054055, 10.170983050847454, 9.74087662337663, 10.27806506849315, 10.309604810996555, 9.837147540983601], "value": 10.13554054054055, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.000850000000009, 9.664879227053127, 9.303279069767433, 9.436462264150938, 9.527619047619044, 9.05178733031674, 9.902772277227708, 9.262268518518525, 9.710606796116497, 9.216866359446994, 9.220506912442406, 9.66364734299517], "value": 9.436462264150938, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.629457364341091, 11.456259541984736, 11.630930232558136, 11.538865384615383, 11.542153846153829, 11.722578125000013, 11.451812977099223, 11.720078125000015, 11.539019230769233, 11.5849420849421, 11.497068965517244, 11.539788461538452], "value": 11.539788461538452, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.17773743016761, 11.053839779005498, 11.238820224719113, 11.111805555555556, 10.99222527472531, 11.11677777777776, 10.813810810810812, 11.178603351955273, 10.93109289617486, 11.115916666666655, 10.815324324324319, 10.934918032786905], "value": 11.053839779005498, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.662510548523205, 12.606092436974789, 12.451099585062225, 12.713305084745778, 12.605672268907568, 12.660063291139231, 12.450497925311202, 12.661160337552742, 12.770468085106405, 12.605756302521002, 12.769808510638324, 12.879055793991416], "value": 12.660063291139231, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.835828402366891, 12.424472049689419, 12.053192771084348, 11.908273809523788, 11.97757485029943, 12.122181818181796, 12.052620481927715, 12.12193939393941, 12.122454545454511, 12.196006097560955, 12.423074534161477, 12.12260606060608], "value": 12.122181818181796, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.49724137931034, 11.675933852140064, 11.452595419847334, 11.721523437499997, 11.583918918918894, 11.861818181818157, 11.630232558139529, 11.76676470588233, 11.812066929133856, 11.906230158730141, 11.765784313725495, 11.586814671814674], "value": 11.721523437499997, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.812837837837838, 11.301016949152535, 10.930300546448063, 11.236966292134833, 11.115083333333354, 11.50063218390805, 11.23955056179775, 11.240505617977556, 11.561329479768789, 11.111194444444441, 11.114111111111116, 10.933879781420773], "value": 11.236966292134833, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.784350000000001, 6.709700000000001, 6.503150000000001, 6.5375, 6.5818], "value": 6.5596499999999995, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5343.435, 5326.88, 5582.93, 5596.695, 5632.405000000001], "value": 5589.8125, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
11:50:38 INFO - Return code: 0
11:50:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
11:50:38 INFO - # TBPL SUCCESS #
11:50:38 INFO - Running post-action listener: _package_coverage_data
11:50:38 INFO - Running post-action listener: _resource_record_post_action
11:50:38 INFO - [mozharness: 2017-11-30 19:50:38.232253Z] Finished run-tests step (success)
11:50:38 INFO - Running post-run listener: _resource_record_post_run
11:50:38 INFO - Total resource usage - Wall time: 667s; CPU: 36.0%; Read bytes: 6115328; Write bytes: 353554432; Read time: 1732; Write time: 372124
11:50:38 INFO - TinderboxPrint: CPU usage
35.6%
11:50:38 INFO - TinderboxPrint: I/O read bytes / time
6,115,328 / 1,732
11:50:38 INFO - TinderboxPrint: I/O write bytes / time
353,554,432 / 372,124
11:50:38 INFO - TinderboxPrint: CPU idle
3,376.7 (64.4%)
11:50:38 INFO - TinderboxPrint: CPU system
313.4 (6.0%)
11:50:38 INFO - TinderboxPrint: CPU user
1,527.7 (29.1%)
11:50:38 INFO - TinderboxPrint: Swap in / out
0 / 0
11:50:38 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 7327744; Read time: 0; Write time: 1564
11:50:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
11:50:38 INFO - run-tests - Wall time: 648s; CPU: 36.0%; Read bytes: 6111232; Write bytes: 344367104; Read time: 1720; Write time: 370220
11:50:38 INFO - Running post-run listener: _upload_blobber_files
11:50:38 INFO - Blob upload gear active.
11:50:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
11:50:38 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
11:50:38 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
11:50:38 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
11:50:38 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
11:50:38 INFO - SNIMissingWarning
11:50:38 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
11:50:38 INFO - InsecurePlatformWarning
11:50:38 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.)
11:50:38 INFO - SubjectAltNameWarning
11:50:39 INFO - (blobuploader) - INFO - Open directory for files ...
11:50:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
11:50:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:50:39 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:50:39 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
11:50:39 INFO - InsecurePlatformWarning
11:50:39 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.)
11:50:39 INFO - SubjectAltNameWarning
11:50:41 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
11:50:41 INFO - InsecurePlatformWarning
11:50:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
11:50:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:50:41 INFO - (blobuploader) - INFO - Done attempting.
11:50:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
11:50:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:50:41 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:50:42 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
11:50:42 INFO - InsecurePlatformWarning
11:50:42 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.)
11:50:42 INFO - SubjectAltNameWarning
11:50:42 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
11:50:42 INFO - InsecurePlatformWarning
11:50:42 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
11:50:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:50:42 INFO - (blobuploader) - INFO - Done attempting.
11:50:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
11:50:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:50:42 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:50:42 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
11:50:42 INFO - InsecurePlatformWarning
11:50:43 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.)
11:50:43 INFO - SubjectAltNameWarning
11:50:43 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
11:50:43 INFO - InsecurePlatformWarning
11:50:43 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
11:50:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:50:43 INFO - (blobuploader) - INFO - Done attempting.
11:50:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
11:50:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
11:50:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
11:50:43 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
11:50:43 INFO - InsecurePlatformWarning
11:50:43 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.)
11:50:43 INFO - SubjectAltNameWarning
11:50:44 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
11:50:44 INFO - InsecurePlatformWarning
11:50:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
11:50:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
11:50:44 INFO - (blobuploader) - INFO - Done attempting.
11:50:44 INFO - (blobuploader) - INFO - Iteration through files over.
11:50:44 INFO - Return code: 0
11:50:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
11:50:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
11:50:44 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31c8648556b4c6fcd8130574da857a0c40dea23f09aa75315affb7b91ab5700fab10101c08878b5cc589c4e902c216ab25bd83e0e3eb628cfa50f44bcad01828", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11803f272bae12da95b19505d9c46527e4170e5fbd473a92f2e05e4562603150a627e1bd32e2249ceb6af9b919b0a09f34e0ef29ffe19abbae0674f391f116da", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c267abf94907853040c96a5d3b9630936cb7c58756af8e21f8f5b0617e2ed892ccf5bd138ab26abd0de033ca115c870b9de091431a68dbf462ef697a153ed1f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3849e6c3fd16428d4975fd6bbc4062f845d3bff3d01bcba9c3050a7d58cb2d0c0ac6706cd752bfcaa175f3a3c43d7844110cbc32602b9f981cac1951a322cd9b"}
11:50:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
11:50:44 INFO - Writing to file /builds/slave/test/properties/blobber_files
11:50:44 INFO - Contents:
11:50:44 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31c8648556b4c6fcd8130574da857a0c40dea23f09aa75315affb7b91ab5700fab10101c08878b5cc589c4e902c216ab25bd83e0e3eb628cfa50f44bcad01828", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11803f272bae12da95b19505d9c46527e4170e5fbd473a92f2e05e4562603150a627e1bd32e2249ceb6af9b919b0a09f34e0ef29ffe19abbae0674f391f116da", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c267abf94907853040c96a5d3b9630936cb7c58756af8e21f8f5b0617e2ed892ccf5bd138ab26abd0de033ca115c870b9de091431a68dbf462ef697a153ed1f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3849e6c3fd16428d4975fd6bbc4062f845d3bff3d01bcba9c3050a7d58cb2d0c0ac6706cd752bfcaa175f3a3c43d7844110cbc32602b9f981cac1951a322cd9b"}
11:50:44 INFO - Running post-run listener: copy_logs_to_upload_dir
11:50:44 INFO - Copying logs to upload dir...
11:50:44 INFO - mkdir: /builds/slave/test/build/upload/logs
11:50:44 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=729.000325
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 9 secs) (at 2017-11-30 11:50:44.670971) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-30 11:50:44.674658) =========
bash -c 'for file in `ls -1`; do cat $file; done'
in dir /builds/slave/test/properties (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test/properties
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1512070273.598434-74646057
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31c8648556b4c6fcd8130574da857a0c40dea23f09aa75315affb7b91ab5700fab10101c08878b5cc589c4e902c216ab25bd83e0e3eb628cfa50f44bcad01828", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11803f272bae12da95b19505d9c46527e4170e5fbd473a92f2e05e4562603150a627e1bd32e2249ceb6af9b919b0a09f34e0ef29ffe19abbae0674f391f116da", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c267abf94907853040c96a5d3b9630936cb7c58756af8e21f8f5b0617e2ed892ccf5bd138ab26abd0de033ca115c870b9de091431a68dbf462ef697a153ed1f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3849e6c3fd16428d4975fd6bbc4062f845d3bff3d01bcba9c3050a7d58cb2d0c0ac6706cd752bfcaa175f3a3c43d7844110cbc32602b9f981cac1951a322cd9b"}
build_url:https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011365
build_url: 'https://queue.taskcluster.net/v1/task/HX9pUEUdTfarWvM71BOdMQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/31c8648556b4c6fcd8130574da857a0c40dea23f09aa75315affb7b91ab5700fab10101c08878b5cc589c4e902c216ab25bd83e0e3eb628cfa50f44bcad01828", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11803f272bae12da95b19505d9c46527e4170e5fbd473a92f2e05e4562603150a627e1bd32e2249ceb6af9b919b0a09f34e0ef29ffe19abbae0674f391f116da", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9c267abf94907853040c96a5d3b9630936cb7c58756af8e21f8f5b0617e2ed892ccf5bd138ab26abd0de033ca115c870b9de091431a68dbf462ef697a153ed1f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3849e6c3fd16428d4975fd6bbc4062f845d3bff3d01bcba9c3050a7d58cb2d0c0ac6706cd752bfcaa175f3a3c43d7844110cbc32602b9f981cac1951a322cd9b"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-30 11:50:44.704818) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 11:50:44.705130) =========
rm -f oauth.txt
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1512070273.598434-74646057
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004478
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 11:50:44.787207) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-30 11:50:44.787519) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-30 11:50:44.787815) =========
========= Total master_lag: 0.24 =========