builder: mozilla-central_ubuntu64_hw_test-g4-e10s-pgo
slave: talos-linux64-ix-064
starttime: 1510952598.96
results: success (0)
revision: 36cad9c45551e29cfb09dd1238c3f56d52776abf
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:18.961120) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:18.961660) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:18.961941) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:18.978745) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:18.979268) =========
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=a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-17 13:03:18-- 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% 54.4M=0s
2017-11-17 13:03:19 (54.4 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.181121
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:19.179763) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:19.180139) =========
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=a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.021991
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:19.230628) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-17 13:03:19.230968) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 36cad9c45551e29cfb09dd1238c3f56d52776abf --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 36cad9c45551e29cfb09dd1238c3f56d52776abf --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=a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-17 13:03:19,274 truncating revision to first 12 chars
2017-11-17 13:03:19,274 Setting DEBUG logging.
2017-11-17 13:03:19,274 attempt 1/10
2017-11-17 13:03:19,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/36cad9c45551?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-17 13:03:19,827 unpacking tar archive at: mozilla-central-36cad9c45551/testing/mozharness/
program finished with exit code 0
elapsedTime=1.210822
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-17 13:03:20.461678) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:20.462368) =========
script_repo_revision: 36cad9c45551e29cfb09dd1238c3f56d52776abf
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:20.462984) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:20.463414) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-17 13:03:20.523447) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 29 secs) (at 2017-11-17 13:03:20.523850) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
13:03:20 INFO - MultiFileLogger online at 20171117 13:03:20 in /builds/slave/test
13:03:20 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
13:03:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
13:03:20 INFO - {'append_to_log': False,
13:03:20 INFO - 'base_work_dir': '/builds/slave/test',
13:03:20 INFO - 'blob_upload_branch': 'Firefox',
13:03:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
13:03:20 INFO - 'branch': 'Firefox',
13:03:20 INFO - 'buildbot_json_path': 'buildprops.json',
13:03:20 INFO - 'code_coverage': False,
13:03:20 INFO - 'config_files': ('talos/linux_config.py',),
13:03:20 INFO - 'default_actions': ('clobber',
13:03:20 INFO - 'read-buildbot-config',
13:03:20 INFO - 'download-and-extract',
13:03:20 INFO - 'populate-webroot',
13:03:20 INFO - 'create-virtualenv',
13:03:20 INFO - 'install',
13:03:20 INFO - 'setup-mitmproxy',
13:03:20 INFO - 'run-tests'),
13:03:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
13:03:20 INFO - 'disable_ccov_upload': False,
13:03:20 INFO - 'disable_stylo': False,
13:03:20 INFO - 'download_minidump_stackwalk': True,
13:03:20 INFO - 'download_symbols': 'ondemand',
13:03:20 INFO - 'e10s': False,
13:03:20 INFO - 'enable_stylo': False,
13:03:20 INFO - 'enable_webrender': False,
13:03:20 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
13:03:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
13:03:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
13:03:20 INFO - 'gecko_profile': False,
13:03:20 INFO - 'gecko_profile_interval': 0,
13:03:20 INFO - 'installer_path': 'installer.exe',
13:03:20 INFO - 'jsd_code_coverage': False,
13:03:20 INFO - 'log_level': 'info',
13:03:20 INFO - 'log_name': 'talos',
13:03:20 INFO - 'log_to_console': True,
13:03:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
13:03:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
13:03:20 INFO - 'opt_config_files': (),
13:03:20 INFO - 'pip_index': False,
13:03:20 INFO - 'suite': 'g4-e10s',
13:03:20 INFO - 'system_bits': '32',
13:03:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
13:03:20 INFO - 'title': 'talos-linux64-ix-064',
13:03:20 INFO - 'tooltool_cache': '/builds/tooltool_cache',
13:03:20 INFO - 'use_talos_json': True,
13:03:20 INFO - 'verify': 'False',
13:03:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
13:03:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
13:03:20 INFO - 'work_dir': 'build'}
13:03:20 INFO - [mozharness: 2017-11-17 21:03:20.701648Z] Running clobber step.
13:03:20 INFO - Running pre-action listener: _resource_record_pre_action
13:03:20 INFO - Running main action method: clobber
13:03:20 INFO - rmtree: /builds/slave/test/build
13:03:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
13:03:21 INFO - Running post-action listener: _resource_record_post_action
13:03:21 INFO - [mozharness: 2017-11-17 21:03:21.811676Z] Finished clobber step (success)
13:03:21 INFO - [mozharness: 2017-11-17 21:03:21.811772Z] Running read-buildbot-config step.
13:03:21 INFO - Running pre-action listener: _resource_record_pre_action
13:03:21 INFO - Running main action method: read_buildbot_config
13:03:21 INFO - Using buildbot properties:
13:03:21 INFO - {
13:03:21 INFO - "project": "",
13:03:21 INFO - "product": "firefox",
13:03:21 INFO - "who": "ffxbld@noreply.mozilla.org",
13:03:21 INFO - "installer_path": "public/build/target.tar.bz2",
13:03:21 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
13:03:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
13:03:21 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-e10s",
13:03:21 INFO - "stage_platform": "linux64-pgo",
13:03:21 INFO - "basedir": "/builds/slave/test",
13:03:21 INFO - "buildnumber": 135,
13:03:21 INFO - "platform": "ubuntu64_hw",
13:03:21 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
13:03:21 INFO - "slavebuilddir": "test",
13:03:21 INFO - "taskId": "Ml2BYDs8QkKD7OZTFxkPSQ",
13:03:21 INFO - "branch": "mozilla-central",
13:03:21 INFO - "script_repo_revision": "production",
13:03:21 INFO - "revision": "36cad9c45551e29cfb09dd1238c3f56d52776abf",
13:03:21 INFO - "slavename": "talos-linux64-ix-064",
13:03:21 INFO - "repo_path": "mozilla-central"
13:03:21 INFO - }
13:03:21 INFO - Finding installer, test and symbols from parent task.
13:03:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ml2BYDs8QkKD7OZTFxkPSQ'}, attempt #1
13:03:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ml2BYDs8QkKD7OZTFxkPSQ'}, attempt #1
13:03:22 INFO - Task dependencies: J1zi1dgLSe2cS-WsHejegw
13:03:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw'}, attempt #1
13:03:23 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:03:23 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json
13:03:23 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip
13:03:23 INFO - Running post-action listener: _resource_record_post_action
13:03:23 INFO - [mozharness: 2017-11-17 21:03:23.169595Z] Finished read-buildbot-config step (success)
13:03:23 INFO - [mozharness: 2017-11-17 21:03:23.169813Z] Running download-and-extract step.
13:03:23 INFO - Running pre-action listener: _resource_record_pre_action
13:03:23 INFO - Running main action method: download_and_extract
13:03:23 INFO - mkdir: /builds/slave/test/build/tests
13:03: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')]}
13:03:23 INFO - trying https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json
13:03:23 INFO - Downloading https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
13:03:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
13:03:23 INFO - Downloaded 989 bytes.
13:03:23 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
13:03:23 INFO - Using the following test package requirements:
13:03:23 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
13:03:23 INFO - u'common': [u'target.common.tests.zip'],
13:03:23 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
13:03:23 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
13:03:23 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
13:03:23 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
13:03:23 INFO - u'mozbase': [u'target.common.tests.zip'],
13:03:23 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
13:03:23 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
13:03:23 INFO - u'web-platform': [u'target.common.tests.zip',
13:03:23 INFO - u'target.web-platform.tests.tar.gz'],
13:03:23 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
13:03:23 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
13:03:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.common.tests.zip
13:03:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.common.tests.zip'}, attempt #1
13:03:23 INFO - Fetch https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.common.tests.zip into memory
13:03:24 INFO - Content-Length response header: 59740113
13:03:24 INFO - Bytes received: 59740113
13:03:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.talos.tests.zip
13:03:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
13:03:35 INFO - Fetch https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.talos.tests.zip into memory
13:03:36 INFO - Content-Length response header: 18771831
13:03:36 INFO - Bytes received: 18771831
13:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:37 INFO - trying https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:03:37 INFO - Downloading https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
13:03:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
13:03:38 INFO - Downloaded 63334895 bytes.
13:03:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:03:38 INFO - mkdir: /builds/slave/test/properties
13:03:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
13:03:38 INFO - Writing to file /builds/slave/test/properties/build_url
13:03:38 INFO - Contents:
13:03:38 INFO - build_url:https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:03:38 INFO - Running post-action listener: _resource_record_post_action
13:03:38 INFO - Running post-action listener: find_tests_for_verification
13:03:38 INFO - Running post-action listener: set_extra_try_arguments
13:03:38 INFO - [mozharness: 2017-11-17 21:03:38.232147Z] Finished download-and-extract step (success)
13:03:38 INFO - [mozharness: 2017-11-17 21:03:38.232243Z] Running populate-webroot step.
13:03:38 INFO - Running pre-action listener: _resource_record_pre_action
13:03:38 INFO - Running main action method: populate_webroot
13:03:38 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
13:03:38 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['tresize',
13:03:38 INFO - 'tcanvasmark']},
13:03:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
13:03:38 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['dromaeo_css',
13:03:38 INFO - 'kraken']},
13:03:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:03:38 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:03:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'tests': ['damp', 'tps']},
13:03:38 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['damp', 'tps']},
13:03:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
13:03:38 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['dromaeo_dom']},
13:03:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
13:03:38 INFO - 'glvideo',
13:03:38 INFO - 'displaylist_mutate']},
13:03:38 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['basic_compositor_video',
13:03:38 INFO - 'glvideo']},
13:03:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
13:03:38 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['ts_paint_webext',
13:03:38 INFO - 'tp5o_webext']},
13:03:38 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
13:03:38 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:03:38 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:03:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:03:38 INFO - 'talos_options': ['--mitmproxy',
13:03:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:03:38 INFO - '--firstNonBlankPaint'],
13:03:38 INFO - 'tests': ['tp6_google_heavy',
13:03:38 INFO - 'tp6_youtube_heavy',
13:03:38 INFO - 'tp6_amazon_heavy',
13:03:38 INFO - 'tp6_facebook_heavy']},
13:03:38 INFO - 'other-e10s': {'tests': ['a11yr',
13:03:38 INFO - 'ts_paint',
13:03:38 INFO - 'tpaint',
13:03:38 INFO - 'sessionrestore',
13:03:38 INFO - 'sessionrestore_many_windows',
13:03:38 INFO - 'sessionrestore_no_auto_restore',
13:03:38 INFO - 'tabpaint',
13:03:38 INFO - 'cpstartup']},
13:03:38 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['a11yr',
13:03:38 INFO - 'ts_paint',
13:03:38 INFO - 'tpaint',
13:03:38 INFO - 'sessionrestore',
13:03:38 INFO - 'sessionrestore_many_windows',
13:03:38 INFO - 'sessionrestore_no_auto_restore',
13:03:38 INFO - 'tabpaint',
13:03:38 INFO - 'cpstartup']},
13:03:38 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
13:03:38 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
13:03:38 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['perf_reftest_singletons']},
13:03:38 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['perf_reftest']},
13:03:38 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
13:03:38 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['speedometer']},
13:03:38 INFO - 'svgr-e10s': {'tests': ['tsvgx',
13:03:38 INFO - 'tsvgr_opacity',
13:03:38 INFO - 'tart',
13:03:38 INFO - 'tscrollx',
13:03:38 INFO - 'tsvg_static']},
13:03:38 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['tsvgx',
13:03:38 INFO - 'tsvgr_opacity',
13:03:38 INFO - 'tart',
13:03:38 INFO - 'tscrollx',
13:03:38 INFO - 'tsvg_static']},
13:03:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
13:03:38 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'talos_options': ['--disable-stylo'],
13:03:38 INFO - 'tests': ['tp5o']},
13:03:38 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:03:38 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:03:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:03:38 INFO - 'talos_options': ['--mitmproxy',
13:03:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:03:38 INFO - '--firstNonBlankPaint'],
13:03:38 INFO - 'tests': ['tp6_google',
13:03:38 INFO - 'tp6_youtube',
13:03:38 INFO - 'tp6_amazon',
13:03:38 INFO - 'tp6_facebook']},
13:03:38 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:03:38 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:03:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:03:38 INFO - 'talos_options': ['--disable-stylo',
13:03:38 INFO - '--mitmproxy',
13:03:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:03:38 INFO - '--firstNonBlankPaint'],
13:03:38 INFO - 'tests': ['tp6_google',
13:03:38 INFO - 'tp6_youtube',
13:03:38 INFO - 'tp6_amazon',
13:03:38 INFO - 'tp6_facebook']},
13:03:38 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:03:38 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:03:38 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:03:38 INFO - 'talos_options': ['--stylo-threads=1',
13:03:38 INFO - '--mitmproxy',
13:03:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:03:38 INFO - '--firstNonBlankPaint'],
13:03:38 INFO - 'tests': ['tp6_google',
13:03:38 INFO - 'tp6_youtube',
13:03:38 INFO - 'tp6_amazon',
13:03:38 INFO - 'tp6_facebook']},
13:03:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'talos_options': ['--xperf_path',
13:03:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:03:38 INFO - 'tests': ['tp5n']},
13:03:38 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:03:38 INFO - 'talos_options': ['--disable-stylo',
13:03:38 INFO - '--xperf_path',
13:03:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:03:38 INFO - 'tests': ['tp5n']}}}
13:03:38 INFO - Running post-action listener: _resource_record_post_action
13:03:38 INFO - [mozharness: 2017-11-17 21:03:38.250794Z] Finished populate-webroot step (success)
13:03:38 INFO - [mozharness: 2017-11-17 21:03:38.250888Z] Running create-virtualenv step.
13:03:38 INFO - Running pre-action listener: _resource_record_pre_action
13:03:38 INFO - Running main action method: create_virtualenv
13:03:38 INFO - Creating virtualenv /builds/slave/test/build/venv
13:03:38 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
13:03:38 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
13:03:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
13:03:38 INFO - Using real prefix '/usr'
13:03:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python
13:03:38 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
13:03:40 INFO - Installing setuptools, pip, wheel...done.
13:03:40 INFO - Return code: 0
13:03:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
13:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:40 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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:40 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
13:03:40 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
13:03:40 INFO - Using env: {'DISPLAY': ':0',
13:03:40 INFO - 'HOME': '/home/cltbld',
13:03:40 INFO - 'LANG': 'en_US.UTF-8',
13:03:40 INFO - 'LANGUAGE': 'en_US:en',
13:03:40 INFO - 'LOGNAME': 'cltbld',
13:03:40 INFO - 'MAIL': '/var/mail/cltbld',
13:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:40 INFO - 'MOZ_NO_REMOTE': '1',
13:03:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:40 INFO - 'NO_EM_RESTART': '1',
13:03:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:40 INFO - 'PWD': '/builds/slave/test',
13:03:40 INFO - 'SHELL': '/bin/bash',
13:03:40 INFO - 'SHLVL': '1',
13:03:40 INFO - 'TERM': 'linux',
13:03:40 INFO - 'TMOUT': '86400',
13:03:40 INFO - 'USER': 'cltbld',
13:03:40 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:40 INFO - '_': '/tools/buildbot/bin/python'}
13:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
13:03:40 INFO - Return code: 0
13:03:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
13:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:40 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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:40 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
13:03:40 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
13:03:40 INFO - Using env: {'DISPLAY': ':0',
13:03:40 INFO - 'HOME': '/home/cltbld',
13:03:40 INFO - 'LANG': 'en_US.UTF-8',
13:03:40 INFO - 'LANGUAGE': 'en_US:en',
13:03:40 INFO - 'LOGNAME': 'cltbld',
13:03:40 INFO - 'MAIL': '/var/mail/cltbld',
13:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:40 INFO - 'MOZ_NO_REMOTE': '1',
13:03:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:40 INFO - 'NO_EM_RESTART': '1',
13:03:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:40 INFO - 'PWD': '/builds/slave/test',
13:03:40 INFO - 'SHELL': '/bin/bash',
13:03:40 INFO - 'SHLVL': '1',
13:03:40 INFO - 'TERM': 'linux',
13:03:40 INFO - 'TMOUT': '86400',
13:03:40 INFO - 'USER': 'cltbld',
13:03:40 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:40 INFO - '_': '/tools/buildbot/bin/python'}
13:03:41 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:41 INFO - Collecting psutil>=3.1.1
13:03:42 INFO - Installing collected packages: psutil
13:03:42 INFO - Successfully installed psutil-3.1.1
13:03:42 INFO - Return code: 0
13:03:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
13:03:42 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')]}
13:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:42 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')]}
13:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:42 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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:42 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
13:03:42 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
13:03:42 INFO - Using env: {'DISPLAY': ':0',
13:03:42 INFO - 'HOME': '/home/cltbld',
13:03:42 INFO - 'LANG': 'en_US.UTF-8',
13:03:42 INFO - 'LANGUAGE': 'en_US:en',
13:03:42 INFO - 'LOGNAME': 'cltbld',
13:03:42 INFO - 'MAIL': '/var/mail/cltbld',
13:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:42 INFO - 'MOZ_NO_REMOTE': '1',
13:03:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:42 INFO - 'NO_EM_RESTART': '1',
13:03:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:42 INFO - 'PWD': '/builds/slave/test',
13:03:42 INFO - 'SHELL': '/bin/bash',
13:03:42 INFO - 'SHLVL': '1',
13:03:42 INFO - 'TERM': 'linux',
13:03:42 INFO - 'TMOUT': '86400',
13:03:42 INFO - 'USER': 'cltbld',
13:03:42 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:42 INFO - '_': '/tools/buildbot/bin/python'}
13:03:42 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:42 INFO - Collecting mozsystemmonitor==0.3
13:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
13:03:43 INFO - Installing collected packages: mozsystemmonitor
13:03:43 INFO - Successfully installed mozsystemmonitor-0.3
13:03:43 INFO - Return code: 0
13:03:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
13:03:43 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')]}
13:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:43 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')]}
13:03:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:43 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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:43 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
13:03:43 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
13:03:43 INFO - Using env: {'DISPLAY': ':0',
13:03:43 INFO - 'HOME': '/home/cltbld',
13:03:43 INFO - 'LANG': 'en_US.UTF-8',
13:03:43 INFO - 'LANGUAGE': 'en_US:en',
13:03:43 INFO - 'LOGNAME': 'cltbld',
13:03:43 INFO - 'MAIL': '/var/mail/cltbld',
13:03:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:43 INFO - 'MOZ_NO_REMOTE': '1',
13:03:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:43 INFO - 'NO_EM_RESTART': '1',
13:03:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:43 INFO - 'PWD': '/builds/slave/test',
13:03:43 INFO - 'SHELL': '/bin/bash',
13:03:43 INFO - 'SHLVL': '1',
13:03:43 INFO - 'TERM': 'linux',
13:03:43 INFO - 'TMOUT': '86400',
13:03:43 INFO - 'USER': 'cltbld',
13:03:43 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:43 INFO - '_': '/tools/buildbot/bin/python'}
13:03:44 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:44 INFO - Collecting jsonschema==2.5.1
13:03:45 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
13:03:45 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
13:03:46 INFO - Installing collected packages: functools32, jsonschema
13:03:46 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
13:03:46 INFO - Return code: 0
13:03:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
13:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
13:03:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
13:03:46 INFO - Using env: {'DISPLAY': ':0',
13:03:46 INFO - 'HOME': '/home/cltbld',
13:03:46 INFO - 'LANG': 'en_US.UTF-8',
13:03:46 INFO - 'LANGUAGE': 'en_US:en',
13:03:46 INFO - 'LOGNAME': 'cltbld',
13:03:46 INFO - 'MAIL': '/var/mail/cltbld',
13:03:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:46 INFO - 'MOZ_NO_REMOTE': '1',
13:03:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:46 INFO - 'NO_EM_RESTART': '1',
13:03:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:46 INFO - 'PWD': '/builds/slave/test',
13:03:46 INFO - 'SHELL': '/bin/bash',
13:03:46 INFO - 'SHLVL': '1',
13:03:46 INFO - 'TERM': 'linux',
13:03:46 INFO - 'TMOUT': '86400',
13:03:46 INFO - 'USER': 'cltbld',
13:03:46 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:46 INFO - '_': '/tools/buildbot/bin/python'}
13:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
13:03:47 INFO - Return code: 0
13:03:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
13:03:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:03:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
13:03:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
13:03:47 INFO - Using env: {'DISPLAY': ':0',
13:03:47 INFO - 'HOME': '/home/cltbld',
13:03:47 INFO - 'LANG': 'en_US.UTF-8',
13:03:47 INFO - 'LANGUAGE': 'en_US:en',
13:03:47 INFO - 'LOGNAME': 'cltbld',
13:03:47 INFO - 'MAIL': '/var/mail/cltbld',
13:03:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:47 INFO - 'MOZ_NO_REMOTE': '1',
13:03:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:47 INFO - 'NO_EM_RESTART': '1',
13:03:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:47 INFO - 'PWD': '/builds/slave/test',
13:03:47 INFO - 'SHELL': '/bin/bash',
13:03:47 INFO - 'SHLVL': '1',
13:03:47 INFO - 'TERM': 'linux',
13:03:47 INFO - 'TMOUT': '86400',
13:03:47 INFO - 'USER': 'cltbld',
13:03:47 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:47 INFO - '_': '/tools/buildbot/bin/python'}
13:03:47 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:47 INFO - Collecting blobuploader==1.2.4
13:03:48 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
13:03:49 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
13:03:50 INFO - Installing collected packages: requests, docopt, blobuploader
13:03:51 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
13:03:51 INFO - Return code: 0
13:03:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:03:51 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')]}
13:03:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:51 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')]}
13:03:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:51 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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:51 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
13:03:51 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
13:03:51 INFO - Using env: {'DISPLAY': ':0',
13:03:51 INFO - 'HOME': '/home/cltbld',
13:03:51 INFO - 'LANG': 'en_US.UTF-8',
13:03:51 INFO - 'LANGUAGE': 'en_US:en',
13:03:51 INFO - 'LOGNAME': 'cltbld',
13:03:51 INFO - 'MAIL': '/var/mail/cltbld',
13:03:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:51 INFO - 'MOZ_NO_REMOTE': '1',
13:03:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:51 INFO - 'NO_EM_RESTART': '1',
13:03:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:51 INFO - 'PWD': '/builds/slave/test',
13:03:51 INFO - 'SHELL': '/bin/bash',
13:03:51 INFO - 'SHLVL': '1',
13:03:51 INFO - 'TERM': 'linux',
13:03:51 INFO - 'TMOUT': '86400',
13:03:51 INFO - 'USER': 'cltbld',
13:03:51 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:51 INFO - '_': '/tools/buildbot/bin/python'}
13:03:51 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:51 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:03:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:03:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:03:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:03:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:03:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:03:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:03:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:03:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:03:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:03:53 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:03:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:03:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:03:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:03:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:03:54 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:03:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:03:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
13:03:54 INFO - Running setup.py install for manifestparser: started
13:03:55 INFO - Running setup.py install for manifestparser: finished with status 'done'
13:03:55 INFO - Running setup.py install for mozcrash: started
13:03:55 INFO - Running setup.py install for mozcrash: finished with status 'done'
13:03:55 INFO - Running setup.py install for mozdebug: started
13:03:55 INFO - Running setup.py install for mozdebug: finished with status 'done'
13:03:55 INFO - Running setup.py install for mozdevice: started
13:03:55 INFO - Running setup.py install for mozdevice: finished with status 'done'
13:03:55 INFO - Running setup.py install for mozfile: started
13:03:55 INFO - Running setup.py install for mozfile: finished with status 'done'
13:03:55 INFO - Running setup.py install for mozhttpd: started
13:03:56 INFO - Running setup.py install for mozhttpd: finished with status 'done'
13:03:56 INFO - Running setup.py install for mozinfo: started
13:03:56 INFO - Running setup.py install for mozinfo: finished with status 'done'
13:03:56 INFO - Running setup.py install for mozInstall: started
13:03:56 INFO - Running setup.py install for mozInstall: finished with status 'done'
13:03:56 INFO - Running setup.py install for mozleak: started
13:03:56 INFO - Running setup.py install for mozleak: finished with status 'done'
13:03:56 INFO - Running setup.py install for mozlog: started
13:03:57 INFO - Running setup.py install for mozlog: finished with status 'done'
13:03:57 INFO - Running setup.py install for moznetwork: started
13:03:57 INFO - Running setup.py install for moznetwork: finished with status 'done'
13:03:57 INFO - Running setup.py install for mozprocess: started
13:03:57 INFO - Running setup.py install for mozprocess: finished with status 'done'
13:03:57 INFO - Running setup.py install for mozprofile: started
13:03:57 INFO - Running setup.py install for mozprofile: finished with status 'done'
13:03:57 INFO - Running setup.py install for mozrunner: started
13:03:57 INFO - Running setup.py install for mozrunner: finished with status 'done'
13:03:57 INFO - Running setup.py install for mozscreenshot: started
13:03:58 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
13:03:58 INFO - Running setup.py install for moztest: started
13:03:58 INFO - Running setup.py install for moztest: finished with status 'done'
13:03:58 INFO - Running setup.py install for mozversion: started
13:03:58 INFO - Running setup.py install for mozversion: finished with status 'done'
13:03:58 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
13:03:58 INFO - Return code: 0
13:03:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:03:58 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')]}
13:03:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:03:58 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')]}
13:03:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:03:58 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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:03:58 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
13:03:58 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
13:03:58 INFO - Using env: {'DISPLAY': ':0',
13:03:58 INFO - 'HOME': '/home/cltbld',
13:03:58 INFO - 'LANG': 'en_US.UTF-8',
13:03:58 INFO - 'LANGUAGE': 'en_US:en',
13:03:58 INFO - 'LOGNAME': 'cltbld',
13:03:58 INFO - 'MAIL': '/var/mail/cltbld',
13:03:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:03:58 INFO - 'MOZ_NO_REMOTE': '1',
13:03:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:03:58 INFO - 'NO_EM_RESTART': '1',
13:03:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:03:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:03:58 INFO - 'PWD': '/builds/slave/test',
13:03:58 INFO - 'SHELL': '/bin/bash',
13:03:58 INFO - 'SHLVL': '1',
13:03:58 INFO - 'TERM': 'linux',
13:03:58 INFO - 'TMOUT': '86400',
13:03:58 INFO - 'USER': 'cltbld',
13:03:58 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:03:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:03:58 INFO - '_': '/tools/buildbot/bin/python'}
13:03:59 INFO - Ignoring indexes: https://pypi.python.org/simple
13:03:59 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:03:59 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))
13:03:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:03:59 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))
13:03:59 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:04:00 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))
13:04:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:04:00 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))
13:04:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:04:00 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))
13:04:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:04:00 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))
13:04:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:04:00 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))
13:04:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:04:01 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))
13:04:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:04:01 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))
13:04:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:04:01 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))
13:04:01 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:04:01 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))
13:04:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:04:01 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))
13:04:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:04:02 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))
13:04:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:04:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
13:04:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:04:02 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))
13:04:02 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:04:02 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))
13:04:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:04:02 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))
13:04:02 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
13:04:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
13:04:03 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))
13:04:03 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))
13:04:03 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))
13:04:03 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))
13:04:03 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))
13:04:03 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))
13:04:03 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
13:04:05 INFO - Installing collected packages: six, blessings
13:04:05 INFO - Successfully installed blessings-1.6 six-1.10.0
13:04:05 INFO - Return code: 0
13:04:05 INFO - Done creating virtualenv /builds/slave/test/build/venv.
13:04:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:04:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:04:05 INFO - Reading from file tmpfile_stdout
13:04:05 INFO - Reading from file tmpfile_stderr
13:04:05 INFO - Current package versions:
13:04:05 INFO - blessings == 1.6
13:04:05 INFO - blobuploader == 1.2.4
13:04:05 INFO - docopt == 0.6.1
13:04:05 INFO - functools32 == 3.2.3.post2
13:04:05 INFO - jsonschema == 2.5.1
13:04:05 INFO - manifestparser == 1.2
13:04:05 INFO - mozInstall == 1.14
13:04:05 INFO - mozcrash == 1.0
13:04:05 INFO - mozdebug == 0.1
13:04:05 INFO - mozdevice == 0.51
13:04:05 INFO - mozfile == 1.2
13:04:05 INFO - mozhttpd == 0.7
13:04:05 INFO - mozinfo == 0.10
13:04:05 INFO - mozleak == 0.1
13:04:05 INFO - mozlog == 3.5
13:04:05 INFO - moznetwork == 0.27
13:04:05 INFO - mozprocess == 0.25
13:04:05 INFO - mozprofile == 0.29
13:04:05 INFO - mozrunner == 6.13
13:04:05 INFO - mozscreenshot == 0.1
13:04:05 INFO - mozsystemmonitor == 0.3
13:04:05 INFO - moztest == 0.8
13:04:05 INFO - mozversion == 1.4
13:04:05 INFO - psutil == 3.1.1
13:04:05 INFO - requests == 1.2.3
13:04:05 INFO - six == 1.10.0
13:04:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:04:05 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')]}
13:04:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:05 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')]}
13:04:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:05 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 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:04:05 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
13:04:05 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
13:04:05 INFO - Using env: {'DISPLAY': ':0',
13:04:05 INFO - 'HOME': '/home/cltbld',
13:04:05 INFO - 'LANG': 'en_US.UTF-8',
13:04:05 INFO - 'LANGUAGE': 'en_US:en',
13:04:05 INFO - 'LOGNAME': 'cltbld',
13:04:05 INFO - 'MAIL': '/var/mail/cltbld',
13:04:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:05 INFO - 'MOZ_NO_REMOTE': '1',
13:04:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:05 INFO - 'NO_EM_RESTART': '1',
13:04:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:05 INFO - 'PWD': '/builds/slave/test',
13:04:05 INFO - 'SHELL': '/bin/bash',
13:04:05 INFO - 'SHLVL': '1',
13:04:05 INFO - 'TERM': 'linux',
13:04:05 INFO - 'TMOUT': '86400',
13:04:05 INFO - 'USER': 'cltbld',
13:04:05 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:04:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:05 INFO - '_': '/tools/buildbot/bin/python'}
13:04:06 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:06 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))
13:04:06 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))
13:04:06 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))
13:04:06 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))
13:04:06 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))
13:04:06 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))
13:04:06 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))
13:04:06 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))
13:04:06 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))
13:04:06 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
13:04:07 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:04:08 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
13:04:08 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))
13:04:08 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))
13:04:08 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))
13:04:08 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))
13:04:09 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
13:04:09 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:04:10 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
13:04:10 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:04:11 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
13:04:11 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:04:12 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
13:04:12 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
13:04:12 INFO - Found existing installation: requests 1.2.3
13:04:12 INFO - Uninstalling requests-1.2.3:
13:04:12 INFO - Successfully uninstalled requests-1.2.3
13:04:12 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
13:04:13 INFO - Return code: 0
13:04:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
13:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:04:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:04:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a58d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a36a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1bd81a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163', '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
13:04:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
13:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
13:04:13 INFO - Using env: {'DISPLAY': ':0',
13:04:13 INFO - 'HOME': '/home/cltbld',
13:04:13 INFO - 'LANG': 'en_US.UTF-8',
13:04:13 INFO - 'LANGUAGE': 'en_US:en',
13:04:13 INFO - 'LOGNAME': 'cltbld',
13:04:13 INFO - 'MAIL': '/var/mail/cltbld',
13:04:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:13 INFO - 'MOZ_NO_REMOTE': '1',
13:04:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:13 INFO - 'NO_EM_RESTART': '1',
13:04:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:13 INFO - 'PWD': '/builds/slave/test',
13:04:13 INFO - 'SHELL': '/bin/bash',
13:04:13 INFO - 'SHLVL': '1',
13:04:13 INFO - 'TERM': 'linux',
13:04:13 INFO - 'TMOUT': '86400',
13:04:13 INFO - 'USER': 'cltbld',
13:04:13 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:04:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:13 INFO - '_': '/tools/buildbot/bin/python'}
13:04:13 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
13:04:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
13:04:13 INFO - Return code: 0
13:04:13 INFO - Running post-action listener: _resource_record_post_action
13:04:13 INFO - Running post-action listener: _start_resource_monitoring
13:04:13 INFO - Starting resource monitoring.
13:04:13 INFO - [mozharness: 2017-11-17 21:04:13.531270Z] Finished create-virtualenv step (success)
13:04:13 INFO - [mozharness: 2017-11-17 21:04:13.531782Z] Running install step.
13:04:13 INFO - Running pre-action listener: _resource_record_pre_action
13:04:13 INFO - Running main action method: install
13:04:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:04:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:04:14 INFO - Reading from file tmpfile_stdout
13:04:14 INFO - Reading from file tmpfile_stderr
13:04:14 INFO - Detecting whether we're running mozinstall >=1.0...
13:04:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
13:04:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
13:04:14 INFO - Reading from file tmpfile_stdout
13:04:14 INFO - Output received:
13:04:14 INFO - Usage: mozinstall [options] installer
13:04:14 INFO - Options:
13:04:14 INFO - -h, --help show this help message and exit
13:04:14 INFO - -d DEST, --destination=DEST
13:04:14 INFO - Directory to install application into. [default:
13:04:14 INFO - "/builds/slave/test"]
13:04:14 INFO - --app=APP Application being installed. [default: firefox]
13:04:14 INFO - mkdir: /builds/slave/test/build/application
13:04:14 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']
13:04:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
13:04:32 INFO - Reading from file tmpfile_stdout
13:04:32 INFO - Output received:
13:04:32 INFO - /builds/slave/test/build/application/firefox/firefox
13:04:32 INFO - Running post-action listener: _resource_record_post_action
13:04:32 INFO - [mozharness: 2017-11-17 21:04:32.354252Z] Finished install step (success)
13:04:32 INFO - [mozharness: 2017-11-17 21:04:32.354368Z] Running setup-mitmproxy step.
13:04:32 INFO - Running pre-action listener: _resource_record_pre_action
13:04:32 INFO - Running main action method: setup_mitmproxy
13:04:32 INFO - Skipping: mitmproxy is not required
13:04:32 INFO - Running post-action listener: _resource_record_post_action
13:04:32 INFO - [mozharness: 2017-11-17 21:04:32.354880Z] Finished setup-mitmproxy step (success)
13:04:32 INFO - [mozharness: 2017-11-17 21:04:32.354980Z] Running run-tests step.
13:04:32 INFO - Running pre-action listener: _resource_record_pre_action
13:04:32 INFO - Running pre-action listener: _set_gcov_prefix
13:04:32 INFO - Running main action method: run_tests
13:04:32 WARNING - Try message not found.
13:04:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
13:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
13:04:32 INFO - Python 2.7.3
13:04:32 INFO - Return code: 0
13:04:32 INFO - grabbing minidump binary from tooltool
13:04:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:04:32 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bd5320>, '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 0x1bd6220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bd66d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
13:04:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
13:04:32 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
13:04:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
13:04:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
13:04:32 INFO - Return code: 0
13:04:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
13:04:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
13:04:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
13:04:32 INFO - ENV: RUST_BACKTRACE is now full
13:04:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
13:04:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
13:04:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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
13:04:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-064 --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
13:04:32 INFO - Using env: {'DISPLAY': ':0',
13:04:32 INFO - 'HOME': '/home/cltbld',
13:04:32 INFO - 'LANG': 'en_US.UTF-8',
13:04:32 INFO - 'LANGUAGE': 'en_US:en',
13:04:32 INFO - 'LOGNAME': 'cltbld',
13:04:32 INFO - 'MAIL': '/var/mail/cltbld',
13:04:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:04:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:32 INFO - 'MOZ_NO_REMOTE': '1',
13:04:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:04:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:32 INFO - 'NO_EM_RESTART': '1',
13:04:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:32 INFO - 'PWD': '/builds/slave/test',
13:04:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:04:32 INFO - 'RUST_BACKTRACE': 'full',
13:04:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:04:32 INFO - 'SHELL': '/bin/bash',
13:04:32 INFO - 'SHLVL': '1',
13:04:32 INFO - 'TERM': 'linux',
13:04:32 INFO - 'TMOUT': '86400',
13:04:32 INFO - 'USER': 'cltbld',
13:04:32 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:32 INFO - '_': '/tools/buildbot/bin/python'}
13:04:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-064', '--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
13:04:32 INFO - mozversion application_buildid: 20171117194157
13:04:32 INFO - mozversion application_changeset: 36cad9c45551e29cfb09dd1238c3f56d52776abf
13:04:32 INFO - mozversion application_display_name: Nightly
13:04:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
13:04:32 INFO - mozversion application_name: Firefox
13:04:32 INFO - mozversion application_remotingname: firefox
13:04:32 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
13:04:32 INFO - mozversion application_vendor: Mozilla
13:04:32 INFO - mozversion application_version: 59.0a1
13:04:32 INFO - mozversion platform_buildid: 20171117194157
13:04:32 INFO - mozversion platform_changeset: 36cad9c45551e29cfb09dd1238c3f56d52776abf
13:04:32 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
13:04:32 INFO - mozversion platform_version: 59.0a1
13:04:32 INFO - using testdate: 1510952672
13:04:32 INFO - actual date: 1510952672
13:04:32 INFO - starting webserver on 'localhost:59147'
13:04:32 INFO - SUITE-START | Running 3 tests
13:04:32 INFO - TEST-START | basic_compositor_video
13:04:32 INFO - Initialising browser for basic_compositor_video test...
13:04:32 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:04:32 INFO - Installing Add-ons
13:04:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59147/getInfo.html -profile /tmp/tmplqqEA_/profile
13:04:32 INFO - TEST-INFO | started process 11033 (/builds/slave/test/build/application/firefox/firefox http://localhost:59147/getInfo.html)
13:04:38 INFO - TEST-INFO | 11033: exit 0
13:04:38 INFO - Browser initialized.
13:04:38 INFO - Running cycle 1/1 for basic_compositor_video test...
13:04:38 INFO - Using env: {'DISPLAY': ':0',
13:04:38 INFO - 'HOME': '/home/cltbld',
13:04:38 INFO - 'JSGC_DISABLE_POISONING': '1',
13:04:38 INFO - 'LANG': 'en_US.UTF-8',
13:04:38 INFO - 'LANGUAGE': 'en_US:en',
13:04:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:04:38 INFO - 'LOGNAME': 'cltbld',
13:04:38 INFO - 'MAIL': '/var/mail/cltbld',
13:04:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:04:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:04:38 INFO - 'MOZ_CRASHREPORTER': '1',
13:04:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:04:38 INFO - 'MOZ_NO_REMOTE': '1',
13:04:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:04:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:38 INFO - 'NO_EM_RESTART': '1',
13:04:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:38 INFO - 'PWD': '/builds/slave/test',
13:04:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:04:38 INFO - 'RUST_BACKTRACE': 'full',
13:04:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:04:38 INFO - 'SHELL': '/bin/bash',
13:04:38 INFO - 'SHLVL': '1',
13:04:38 INFO - 'TERM': 'linux',
13:04:38 INFO - 'TMOUT': '86400',
13:04:38 INFO - 'USER': 'cltbld',
13:04:38 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:04:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:38 INFO - '_': '/tools/buildbot/bin/python'}
13:04:38 INFO - TEST-INFO | started process 11304 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplqqEA_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
13:04:39 INFO - PID 11304 |
13:04:39 INFO - PID 11304 | (/builds/slave/test/build/application/firefox/firefox:11357): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:04:39 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822365470852019 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6696702838063437 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.732681870669746 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7624361233480175 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.043969002695418 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.150549792531121 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.4250913242009142 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3911694915254254 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.771048076923076 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620028089887639 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.420104166666672 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.586269841269843 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.497777777777785 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.114805555555561 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.932068965517233 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.124636363636379 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.766490196078438 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.432142857142857 ms/frame
13:05:25 INFO - PID 11304 |
13:05:25 INFO - PID 11304 | Cycle 1(1): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:06:11 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6813333333333333 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683152627189324 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7347369942196529 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.715523156089194 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 3.745655430711611 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.150860995850623 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.5222241784037576 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.491439790575912 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.64982109227872 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.685085227272731 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.793812949640289 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.417708333333337 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.365208333333337 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.990219780219787 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.989458874458885 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052951807228878 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542461538461541 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111638888888896 ms/frame
13:06:11 INFO - PID 11304 |
13:06:11 INFO - PID 11304 | Cycle 1(2): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:06:58 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6798936170212768 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684987489574648 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7776718009478674 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.764082892416226 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0552094594594585 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.167218749999999 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.4766280417149478 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3785135135135165 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.780703275529867 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.586703910614528 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.03576923076923 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.005300000000007 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.321849056603764 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.758037634408616 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.40107438016531 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.127181818181777 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.720292968749987 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.432200000000012 ms/frame
13:06:58 INFO - PID 11304 |
13:06:58 INFO - PID 11304 | Cycle 1(3): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:07:44 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6893243243243246 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.666895833333333 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7341878612716761 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7167854077253222 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.121510989010988 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.066270325203251 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.542744982290439 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4971678321678277 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.58810055865922 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5555277777777725 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.20539115646258 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 9.664710144927541 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.4114258555133 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754650537634397 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.451099585062225 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907648809523813 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.766980392156869 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.305734463276824 ms/frame
13:07:44 INFO - PID 11304 |
13:07:44 INFO - PID 11304 | Cycle 1(4): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:08:30 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6772750139742874 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6727926421404684 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.803470552884615 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.782669340463458 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.18565550906555 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.034385080645162 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.580208830548924 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5793470483005425 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.671219281663517 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.46651639344262 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.137837837837836 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.641010638297864 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.153475836431227 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.931065573770503 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.713326271186446 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053584337349422 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.81220472440946 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.369573863636354 ms/frame
13:08:30 INFO - PID 11304 |
13:08:30 INFO - PID 11304 | Cycle 1(5): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:09:16 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6798376259798433 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6722658862876256 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.736287615740741 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7530674846625767 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.11587791495199 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8628861003861013 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.4843495934959323 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3733726812816185 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683617424242426 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.452438692098088 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.41947916666667 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 9.808382352941175 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.366553030303033 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.050497237569058 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.935711206896547 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.271871165644185 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.811318897637785 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17477653631287 ms/frame
13:09:16 INFO - PID 11304 |
13:09:16 INFO - PID 11304 | Cycle 1(6): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:10:02 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6840011223344558 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674250000000002 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.754666666666667 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7545219298245611 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.054804054054054 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8838349514563126 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.654494518879418 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6173146473779387 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.747720306513406 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.450217983651225 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.2756506849315 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 9.21792626728111 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.410589353612169 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.872255434782623 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.661054852320667 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.127696969696968 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.76482352941176 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.241544943820239 ms/frame
13:10:02 INFO - PID 11304 |
13:10:02 INFO - PID 11304 | Cycle 1(7): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:10:48 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6942264257481647 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6696452420701167 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7632079905992952 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7167939914163095 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.197027972027972 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.143178053830225 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.5589264531435365 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.478513043478262 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.4553454545454585 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.574192200557099 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.239573378839589 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.005175 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.278665413533849 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.116583333333336 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.82273504273503 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.981167664670679 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 12.000099999999977 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.303220338983081 ms/frame
13:10:48 INFO - PID 11304 |
13:10:48 INFO - PID 11304 | Cycle 1(8): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:11:35 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680019596864502 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6740376569037652 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7778406398104265 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7423867595818818 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.138282758620691 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.7747735849056587 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.5379304245283008 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.460190311418688 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.736395793499048 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.422032520325202 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.347482758620691 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.530394736842114 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.58399613899613 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.869646739130431 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.87851931330472 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.500968749999993 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583880308880303 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.173463687150853 ms/frame
13:11:35 INFO - PID 11304 |
13:11:35 INFO - PID 11304 | Cycle 1(9): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:12:21 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6812521008403363 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6716123642439429 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7650970588235293 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7636992945326277 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.209039270687237 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.19392033542977 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.572571428571432 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5472517730496413 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.546432532347502 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.541066481994462 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.491818181818182 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.101540404040401 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.322339622641516 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.8112162162162 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.45242738589213 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977664670658664 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58372586872586 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.23620786516857 ms/frame
13:12:21 INFO - PID 11304 |
13:12:21 INFO - PID 11304 | Cycle 1(10): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:13:07 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822197309417042 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668386155129274 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7880780691299167 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7674867491166077 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.054817567567566 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.033336693548387 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.448913793103449 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.40200680272109 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.726059160305347 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.650141242937847 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.638989361702127 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 10.05452261306532 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.237003745318361 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.812567567567552 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.879184549356218 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.349197530864195 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.765568627450985 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932923497267751 ms/frame
13:13:07 INFO - PID 11304 |
13:13:07 INFO - PID 11304 | Cycle 1(11): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:13:53 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_startup = 1.67917459429211 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6671166666666666 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7955655296229804 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7261561691113028 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_startup = 4.17848885793872 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.6770772058823527 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_startup = 3.509690058479532 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5098947368421043 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.781570327552983 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.496552197802195 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_startup = 10.95124087591242 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.480p.60fps.webm_scale_2_inclip = 9.438537735849044 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.497835249042136 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.111777777777792 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.501520833333325 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.198292682926855 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.766686274509823 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.364602272727263 ms/frame
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | Cycle 1(12): loaded http://localhost:59147/tests/video/video_playback.html (next: http://localhost:59147/tests/video/video_playback.html)
13:13:53 INFO - PID 11304 | __start_tp_report
13:13:53 INFO - PID 11304 | _x_x_mozilla_page_load
13:13:53 INFO - PID 11304 | _x_x_mozilla_page_load_details
13:13:53 INFO - PID 11304 | |i|pagename|runs|
13:13:53 INFO - PID 11304 | |0;240p.120fps.mp4_scale_1_startup;1.6822365470852019;1.6813333333333333;1.6798936170212768;1.6893243243243246;1.6772750139742874;1.6798376259798433;1.6840011223344558;1.6942264257481647;1.680019596864502;1.6812521008403363;1.6822197309417042;1.67917459429211
13:13:53 INFO - PID 11304 | |1;240p.120fps.mp4_scale_1_inclip;1.6696702838063437;1.6683152627189324;1.6684987489574648;1.666895833333333;1.6727926421404684;1.6722658862876256;1.6674250000000002;1.6696452420701167;1.6740376569037652;1.6716123642439429;1.668386155129274;1.6671166666666666
13:13:53 INFO - PID 11304 | |2;240p.120fps.mp4_scale_1.1_startup;1.732681870669746;1.7347369942196529;1.7776718009478674;1.7341878612716761;1.803470552884615;1.736287615740741;1.754666666666667;1.7632079905992952;1.7778406398104265;1.7650970588235293;1.7880780691299167;1.7955655296229804
13:13:53 INFO - PID 11304 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7624361233480175;1.715523156089194;1.764082892416226;1.7167854077253222;1.782669340463458;1.7530674846625767;1.7545219298245611;1.7167939914163095;1.7423867595818818;1.7636992945326277;1.7674867491166077;1.7261561691113028
13:13:53 INFO - PID 11304 | |4;240p.120fps.mp4_scale_2_startup;4.043969002695418;3.745655430711611;4.0552094594594585;4.121510989010988;4.18565550906555;4.11587791495199;4.054804054054054;4.197027972027972;4.138282758620691;4.209039270687237;4.054817567567566;4.17848885793872
13:13:53 INFO - PID 11304 | |5;240p.120fps.mp4_scale_2_inclip;4.150549792531121;4.150860995850623;4.167218749999999;4.066270325203251;4.034385080645162;3.8628861003861013;3.8838349514563126;4.143178053830225;3.7747735849056587;4.19392033542977;4.033336693548387;3.6770772058823527
13:13:53 INFO - PID 11304 | |6;480p.60fps.webm_scale_1_startup;3.4250913242009142;3.5222241784037576;3.4766280417149478;3.542744982290439;3.580208830548924;3.4843495934959323;3.654494518879418;3.5589264531435365;3.5379304245283008;3.572571428571432;3.448913793103449;3.509690058479532
13:13:53 INFO - PID 11304 | |7;480p.60fps.webm_scale_1_inclip;3.3911694915254254;3.491439790575912;3.3785135135135165;3.4971678321678277;3.5793470483005425;3.3733726812816185;3.6173146473779387;3.478513043478262;3.460190311418688;3.5472517730496413;3.40200680272109;3.5098947368421043
13:13:53 INFO - PID 11304 | |8;480p.60fps.webm_scale_1.1_startup;5.771048076923076;5.64982109227872;5.780703275529867;5.58810055865922;5.671219281663517;5.683617424242426;5.747720306513406;5.4553454545454585;5.736395793499048;5.546432532347502;5.726059160305347;5.781570327552983
13:13:53 INFO - PID 11304 | |9;480p.60fps.webm_scale_1.1_inclip;5.620028089887639;5.685085227272731;5.586703910614528;5.5555277777777725;5.46651639344262;5.452438692098088;5.450217983651225;5.574192200557099;5.422032520325202;5.541066481994462;5.650141242937847;5.496552197802195
13:13:53 INFO - PID 11304 | |10;480p.60fps.webm_scale_2_startup;10.420104166666672;10.793812949640289;10.03576923076923;10.20539115646258;10.137837837837836;10.41947916666667;10.2756506849315;10.239573378839589;10.347482758620691;10.491818181818182;10.638989361702127;10.95124087591242
13:13:53 INFO - PID 11304 | |11;480p.60fps.webm_scale_2_inclip;10.586269841269843;10.417708333333337;10.005300000000007;9.664710144927541;10.641010638297864;9.808382352941175;9.21792626728111;10.005175;10.530394736842114;10.101540404040401;10.05452261306532;9.438537735849044
13:13:53 INFO - PID 11304 | |12;1080p.60fps.mp4_scale_1_startup;11.497777777777785;11.365208333333337;11.321849056603764;11.4114258555133;11.153475836431227;11.366553030303033;11.410589353612169;11.278665413533849;11.58399613899613;11.322339622641516;11.237003745318361;11.497835249042136
13:13:53 INFO - PID 11304 | |13;1080p.60fps.mp4_scale_1_inclip;11.114805555555561;10.990219780219787;10.758037634408616;10.754650537634397;10.931065573770503;11.050497237569058;10.872255434782623;11.116583333333336;10.869646739130431;10.8112162162162;10.812567567567552;11.111777777777792
13:13:53 INFO - PID 11304 | |14;1080p.60fps.mp4_scale_1.1_startup;12.932068965517233;12.989458874458885;12.40107438016531;12.451099585062225;12.713326271186446;12.935711206896547;12.661054852320667;12.82273504273503;12.87851931330472;12.45242738589213;12.879184549356218;12.501520833333325
13:13:53 INFO - PID 11304 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.124636363636379;12.052951807228878;12.127181818181777;11.907648809523813;12.053584337349422;12.271871165644185;12.127696969696968;11.981167664670679;12.500968749999993;11.977664670658664;12.349197530864195;12.198292682926855
13:13:53 INFO - PID 11304 | |16;1080p.60fps.mp4_scale_2_startup;11.766490196078438;11.542461538461541;11.720292968749987;11.766980392156869;11.81220472440946;11.811318897637785;11.76482352941176;12.000099999999977;11.583880308880303;11.58372586872586;11.765568627450985;11.766686274509823
13:13:53 INFO - PID 11304 | |17;1080p.60fps.mp4_scale_2_inclip;11.432142857142857;11.111638888888896;11.432200000000012;11.305734463276824;11.369573863636354;11.17477653631287;11.241544943820239;11.303220338983081;11.173463687150853;11.23620786516857;10.932923497267751;11.364602272727263
13:13:53 INFO - PID 11304 | __end_tp_report
13:13:53 INFO - PID 11304 | __start_cc_report
13:13:53 INFO - PID 11304 | _x_x_mozilla_cycle_collect,565
13:13:53 INFO - PID 11304 | __end_cc_report
13:13:53 INFO - PID 11304 | __startTimestamp1510953233903__endTimestamp
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | ------- Summary: start -------
13:13:53 INFO - PID 11304 | Number of tests: 18
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
13:13:53 INFO - PID 11304 | 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
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
13:13:53 INFO - PID 11304 | 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
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.77 stddev:0.03 (1.4%) stddev-sans-first:0.02
13:13:53 INFO - PID 11304 | Values: 1.7 1.7 1.8 1.7 1.8 1.7 1.8 1.8 1.8 1.8 1.8 1.8
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.76 stddev:0.02 (1.3%) stddev-sans-first:0.02
13:13:53 INFO - PID 11304 | Values: 1.8 1.7 1.8 1.7 1.8 1.8 1.8 1.7 1.7 1.8 1.8 1.7
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.09 Median:4.13 stddev:0.12 (3.0%) stddev-sans-first:0.13
13:13:53 INFO - PID 11304 | Values: 4.0 3.7 4.1 4.1 4.2 4.1 4.1 4.2 4.1 4.2 4.1 4.2
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.01 Median:4.10 stddev:0.17 (4.2%) stddev-sans-first:0.17
13:13:53 INFO - PID 11304 | Values: 4.2 4.2 4.2 4.1 4.0 3.9 3.9 4.1 3.8 4.2 4.0 3.7
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.53 Median:3.54 stddev:0.06 (1.8%) stddev-sans-first:0.06
13:13:53 INFO - PID 11304 | Values: 3.4 3.5 3.5 3.5 3.6 3.5 3.7 3.6 3.5 3.6 3.4 3.5
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.48 Median:3.49 stddev:0.08 (2.3%) stddev-sans-first:0.08
13:13:53 INFO - PID 11304 | Values: 3.4 3.5 3.4 3.5 3.6 3.4 3.6 3.5 3.5 3.5 3.4 3.5
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.68 Median:5.73 stddev:0.10 (1.8%) stddev-sans-first:0.10
13:13:53 INFO - PID 11304 | Values: 5.8 5.6 5.8 5.6 5.7 5.7 5.7 5.5 5.7 5.5 5.7 5.8
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.54 Median:5.56 stddev:0.09 (1.5%) stddev-sans-first:0.09
13:13:53 INFO - PID 11304 | Values: 5.6 5.7 5.6 5.6 5.5 5.5 5.5 5.6 5.4 5.5 5.7 5.5
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.41 Median:10.42 stddev:0.27 (2.6%) stddev-sans-first:0.28
13:13:53 INFO - PID 11304 | Values: 10.4 10.8 10.0 10.2 10.1 10.4 10.3 10.2 10.3 10.5 10.6 11.0
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.04 Median:10.61 stddev:0.46 (4.3%) stddev-sans-first:0.44
13:13:53 INFO - PID 11304 | Values: 10.6 10.4 10.0 9.7 10.6 9.8 9.2 10.0 10.5 10.1 10.1 9.4
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.37 Median:11.39 stddev:0.12 (1.1%) stddev-sans-first:0.12
13:13:53 INFO - PID 11304 | Values: 11.5 11.4 11.3 11.4 11.2 11.4 11.4 11.3 11.6 11.3 11.2 11.5
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.93 Median:10.96 stddev:0.14 (1.3%) stddev-sans-first:0.13
13:13:53 INFO - PID 11304 | Values: 11.1 11.0 10.8 10.8 10.9 11.1 10.9 11.1 10.9 10.8 10.8 11.1
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.72 Median:12.85 stddev:0.22 (1.7%) stddev-sans-first:0.22
13:13:53 INFO - PID 11304 | Values: 12.9 13.0 12.4 12.5 12.7 12.9 12.7 12.8 12.9 12.5 12.9 12.5
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.14 Median:12.13 stddev:0.17 (1.4%) stddev-sans-first:0.18
13:13:53 INFO - PID 11304 | Values: 12.1 12.1 12.1 11.9 12.1 12.3 12.1 12.0 12.5 12.0 12.3 12.2
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.74 Median:11.77 stddev:0.12 (1.1%) stddev-sans-first:0.13
13:13:53 INFO - PID 11304 | Values: 11.8 11.5 11.7 11.8 11.8 11.8 11.8 12.0 11.6 11.6 11.8 11.8
13:13:53 INFO - PID 11304 |
13:13:53 INFO - PID 11304 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.26 Median:11.30 stddev:0.14 (1.3%) stddev-sans-first:0.14
13:13:53 INFO - PID 11304 | Values: 11.4 11.1 11.4 11.3 11.4 11.2 11.2 11.3 11.2 11.2 10.9 11.4
13:13:53 INFO - PID 11304 | -------- Summary: end --------
13:13:53 INFO - PID 11304 |
13:13:54 INFO - TEST-INFO | 11304: exit 0
13:13:54 INFO - TEST-OK | basic_compositor_video | took 561930ms
13:13:54 INFO - TEST-START | glvideo
13:13:54 INFO - Initialising browser for glvideo test...
13:13:54 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:13:54 INFO - Installing Add-ons
13:13:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59147/getInfo.html -profile /tmp/tmpm3FdDq/profile
13:13:54 INFO - TEST-INFO | started process 12525 (/builds/slave/test/build/application/firefox/firefox http://localhost:59147/getInfo.html)
13:14:00 INFO - TEST-INFO | 12525: exit 0
13:14:00 INFO - Browser initialized.
13:14:00 INFO - Running cycle 1/1 for glvideo test...
13:14:00 INFO - Using env: {'DISPLAY': ':0',
13:14:00 INFO - 'HOME': '/home/cltbld',
13:14:00 INFO - 'JSGC_DISABLE_POISONING': '1',
13:14:00 INFO - 'LANG': 'en_US.UTF-8',
13:14:00 INFO - 'LANGUAGE': 'en_US:en',
13:14:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:14:00 INFO - 'LOGNAME': 'cltbld',
13:14:00 INFO - 'MAIL': '/var/mail/cltbld',
13:14:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:14:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:14:00 INFO - 'MOZ_CRASHREPORTER': '1',
13:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:14:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:14:00 INFO - 'MOZ_NO_REMOTE': '1',
13:14:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:14:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:14:00 INFO - 'NO_EM_RESTART': '1',
13:14:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:14:00 INFO - 'PWD': '/builds/slave/test',
13:14:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:14:00 INFO - 'RUST_BACKTRACE': 'full',
13:14:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:14:00 INFO - 'SHELL': '/bin/bash',
13:14:00 INFO - 'SHLVL': '1',
13:14:00 INFO - 'TERM': 'linux',
13:14:00 INFO - 'TMOUT': '86400',
13:14:00 INFO - 'USER': 'cltbld',
13:14:00 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:14:00 INFO - '_': '/tools/buildbot/bin/python'}
13:14:00 INFO - TEST-INFO | started process 12802 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpm3FdDq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
13:14:01 INFO - PID 12802 |
13:14:01 INFO - PID 12802 | (/builds/slave/test/build/application/firefox/firefox:12856): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:14:01 INFO - PID 12802 |
13:14:03 INFO - PID 12802 | [talos glvideo result] Mean tick time across 100 ticks: 7.802549999999999 ms
13:14:03 INFO - PID 12802 | Cycle 1(1): loaded http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html)
13:14:04 INFO - PID 12802 | [talos glvideo result] Mean tick time across 100 ticks: 6.5686 ms
13:14:04 INFO - PID 12802 | Cycle 1(2): loaded http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html)
13:14:05 INFO - PID 12802 | [talos glvideo result] Mean tick time across 100 ticks: 6.53895 ms
13:14:05 INFO - PID 12802 | Cycle 1(3): loaded http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html)
13:14:07 INFO - PID 12802 | [talos glvideo result] Mean tick time across 100 ticks: 6.699300000000001 ms
13:14:07 INFO - PID 12802 | Cycle 1(4): loaded http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html)
13:14:08 INFO - PID 12802 | [talos glvideo result] Mean tick time across 100 ticks: 6.6857500000000005 ms
13:14:08 INFO - PID 12802 | Cycle 1(5): loaded http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59147/tests/webgl/benchmarks/video/video_upload.html)
13:14:08 INFO - PID 12802 | __start_tp_report
13:14:08 INFO - PID 12802 | _x_x_mozilla_page_load
13:14:08 INFO - PID 12802 | _x_x_mozilla_page_load_details
13:14:08 INFO - PID 12802 | |i|pagename|runs|
13:14:08 INFO - PID 12802 | |0;Mean tick time across 100 ticks: ;7.802549999999999;6.5686;6.53895;6.699300000000001;6.6857500000000005
13:14:08 INFO - PID 12802 | __end_tp_report
13:14:08 INFO - PID 12802 | __start_cc_report
13:14:08 INFO - PID 12802 | _x_x_mozilla_cycle_collect,273
13:14:08 INFO - PID 12802 | __end_cc_report
13:14:08 INFO - PID 12802 | __startTimestamp1510953248626__endTimestamp
13:14:08 INFO - PID 12802 |
13:14:08 INFO - PID 12802 | ------- Summary: start -------
13:14:08 INFO - PID 12802 | Number of tests: 1
13:14:08 INFO - PID 12802 |
13:14:08 INFO - PID 12802 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.86 Median:6.69 stddev:0.53 (8.0%) stddev-sans-first:0.08
13:14:08 INFO - PID 12802 | Values: 7.8 6.6 6.5 6.7 6.7
13:14:08 INFO - PID 12802 | -------- Summary: end --------
13:14:08 INFO - PID 12802 |
13:14:08 INFO - PID 12802 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
13:14:09 INFO - TEST-INFO | 12802: exit 0
13:14:09 INFO - TEST-OK | glvideo | took 14438ms
13:14:09 INFO - TEST-START | displaylist_mutate
13:14:09 INFO - Initialising browser for displaylist_mutate test...
13:14:09 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:14:09 INFO - Installing Add-ons
13:14:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59147/getInfo.html -profile /tmp/tmpHfdVEd/profile
13:14:09 INFO - TEST-INFO | started process 13027 (/builds/slave/test/build/application/firefox/firefox http://localhost:59147/getInfo.html)
13:14:15 INFO - TEST-INFO | 13027: exit 0
13:14:15 INFO - Browser initialized.
13:14:15 INFO - Running cycle 1/1 for displaylist_mutate test...
13:14:15 INFO - Using env: {'DISPLAY': ':0',
13:14:15 INFO - 'HOME': '/home/cltbld',
13:14:15 INFO - 'JSGC_DISABLE_POISONING': '1',
13:14:15 INFO - 'LANG': 'en_US.UTF-8',
13:14:15 INFO - 'LANGUAGE': 'en_US:en',
13:14:15 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:14:15 INFO - 'LOGNAME': 'cltbld',
13:14:15 INFO - 'MAIL': '/var/mail/cltbld',
13:14:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:14:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:14:15 INFO - 'MOZ_CRASHREPORTER': '1',
13:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:14:15 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:14:15 INFO - 'MOZ_NO_REMOTE': '1',
13:14:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:14:15 INFO - 'NO_EM_RESTART': '1',
13:14:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:14:15 INFO - 'PWD': '/builds/slave/test',
13:14:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:14:15 INFO - 'RUST_BACKTRACE': 'full',
13:14:15 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:14:15 INFO - 'SHELL': '/bin/bash',
13:14:15 INFO - 'SHLVL': '1',
13:14:15 INFO - 'TERM': 'linux',
13:14:15 INFO - 'TMOUT': '86400',
13:14:15 INFO - 'USER': 'cltbld',
13:14:15 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163',
13:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:14:15 INFO - '_': '/tools/buildbot/bin/python'}
13:14:15 INFO - TEST-INFO | started process 13299 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHfdVEd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
13:14:16 INFO - PID 13299 |
13:14:16 INFO - PID 13299 | (/builds/slave/test/build/application/firefox/firefox:13351): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:14:16 INFO - PID 13299 |
13:14:21 INFO - PID 13299 | Cycle 1(1): loaded http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html)
13:14:26 INFO - PID 13299 | Cycle 1(2): loaded http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html)
13:14:31 INFO - PID 13299 | Cycle 1(3): loaded http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html)
13:14:36 INFO - PID 13299 | Cycle 1(4): loaded http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html)
13:14:42 INFO - PID 13299 | Cycle 1(5): loaded http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59147/tests/layout/benchmarks/displaylist_mutate.html)
13:14:42 INFO - PID 13299 | __start_tp_report
13:14:42 INFO - PID 13299 | _x_x_mozilla_page_load
13:14:42 INFO - PID 13299 | _x_x_mozilla_page_load_details
13:14:42 INFO - PID 13299 | |i|pagename|runs|
13:14:42 INFO - PID 13299 | |0;/displaylist_mutate.html;4280.915;4521.63;4349.910000000001;4635.07;4600.695000000001
13:14:42 INFO - PID 13299 | __end_tp_report
13:14:42 INFO - PID 13299 | __start_cc_report
13:14:42 INFO - PID 13299 | _x_x_mozilla_cycle_collect,248
13:14:42 INFO - PID 13299 | __end_cc_report
13:14:42 INFO - PID 13299 | __startTimestamp1510953282530__endTimestamp
13:14:42 INFO - PID 13299 |
13:14:42 INFO - PID 13299 | ------- Summary: start -------
13:14:42 INFO - PID 13299 | Number of tests: 1
13:14:42 INFO - PID 13299 |
13:14:42 INFO - PID 13299 | [#0] /displaylist_mutate.html Cycles:5 Average:4477.64 Median:4521.63 stddev:155.63 (3.4%) stddev-sans-first:127.15
13:14:42 INFO - PID 13299 | Values: 4280.9 4521.6 4349.9 4635.1 4600.7
13:14:42 INFO - PID 13299 | -------- Summary: end --------
13:14:42 INFO - PID 13299 |
13:14:43 INFO - PID 13299 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
13:14:43 INFO - TEST-INFO | 13299: exit 0
13:14:43 INFO - TEST-OK | displaylist_mutate | took 34172ms
13:14:43 INFO - SUITE-END | took 610s
13:14:43 INFO - Completed test suite (00:10:10)
13:14:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.649163981145809, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6822365470852019, 1.6813333333333333, 1.6798936170212768, 1.6893243243243246, 1.6772750139742874, 1.6798376259798433, 1.6840011223344558, 1.6942264257481647, 1.680019596864502, 1.6812521008403363, 1.6822197309417042, 1.67917459429211], "value": 1.6812521008403363, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6696702838063437, 1.6683152627189324, 1.6684987489574648, 1.666895833333333, 1.6727926421404684, 1.6722658862876256, 1.6674250000000002, 1.6696452420701167, 1.6740376569037652, 1.6716123642439429, 1.668386155129274, 1.6671166666666666], "value": 1.6684987489574648, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.732681870669746, 1.7347369942196529, 1.7776718009478674, 1.7341878612716761, 1.803470552884615, 1.736287615740741, 1.754666666666667, 1.7632079905992952, 1.7778406398104265, 1.7650970588235293, 1.7880780691299167, 1.7955655296229804], "value": 1.7650970588235293, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7624361233480175, 1.715523156089194, 1.764082892416226, 1.7167854077253222, 1.782669340463458, 1.7530674846625767, 1.7545219298245611, 1.7167939914163095, 1.7423867595818818, 1.7636992945326277, 1.7674867491166077, 1.7261561691113028], "value": 1.7530674846625767, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.043969002695418, 3.745655430711611, 4.0552094594594585, 4.121510989010988, 4.18565550906555, 4.11587791495199, 4.054804054054054, 4.197027972027972, 4.138282758620691, 4.209039270687237, 4.054817567567566, 4.17848885793872], "value": 4.121510989010988, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.150549792531121, 4.150860995850623, 4.167218749999999, 4.066270325203251, 4.034385080645162, 3.8628861003861013, 3.8838349514563126, 4.143178053830225, 3.7747735849056587, 4.19392033542977, 4.033336693548387, 3.6770772058823527], "value": 4.034385080645162, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4250913242009142, 3.5222241784037576, 3.4766280417149478, 3.542744982290439, 3.580208830548924, 3.4843495934959323, 3.654494518879418, 3.5589264531435365, 3.5379304245283008, 3.572571428571432, 3.448913793103449, 3.509690058479532], "value": 3.5379304245283008, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.3911694915254254, 3.491439790575912, 3.3785135135135165, 3.4971678321678277, 3.5793470483005425, 3.3733726812816185, 3.6173146473779387, 3.478513043478262, 3.460190311418688, 3.5472517730496413, 3.40200680272109, 3.5098947368421043], "value": 3.491439790575912, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.771048076923076, 5.64982109227872, 5.780703275529867, 5.58810055865922, 5.671219281663517, 5.683617424242426, 5.747720306513406, 5.4553454545454585, 5.736395793499048, 5.546432532347502, 5.726059160305347, 5.781570327552983], "value": 5.683617424242426, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.620028089887639, 5.685085227272731, 5.586703910614528, 5.5555277777777725, 5.46651639344262, 5.452438692098088, 5.450217983651225, 5.574192200557099, 5.422032520325202, 5.541066481994462, 5.650141242937847, 5.496552197802195], "value": 5.541066481994462, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.420104166666672, 10.793812949640289, 10.03576923076923, 10.20539115646258, 10.137837837837836, 10.41947916666667, 10.2756506849315, 10.239573378839589, 10.347482758620691, 10.491818181818182, 10.638989361702127, 10.95124087591242], "value": 10.347482758620691, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.586269841269843, 10.417708333333337, 10.005300000000007, 9.664710144927541, 10.641010638297864, 9.808382352941175, 9.21792626728111, 10.005175, 10.530394736842114, 10.101540404040401, 10.05452261306532, 9.438537735849044], "value": 10.005300000000007, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.497777777777785, 11.365208333333337, 11.321849056603764, 11.4114258555133, 11.153475836431227, 11.366553030303033, 11.410589353612169, 11.278665413533849, 11.58399613899613, 11.322339622641516, 11.237003745318361, 11.497835249042136], "value": 11.365208333333337, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.114805555555561, 10.990219780219787, 10.758037634408616, 10.754650537634397, 10.931065573770503, 11.050497237569058, 10.872255434782623, 11.116583333333336, 10.869646739130431, 10.8112162162162, 10.812567567567552, 11.111777777777792], "value": 10.872255434782623, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.932068965517233, 12.989458874458885, 12.40107438016531, 12.451099585062225, 12.713326271186446, 12.935711206896547, 12.661054852320667, 12.82273504273503, 12.87851931330472, 12.45242738589213, 12.879184549356218, 12.501520833333325], "value": 12.713326271186446, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.124636363636379, 12.052951807228878, 12.127181818181777, 11.907648809523813, 12.053584337349422, 12.271871165644185, 12.127696969696968, 11.981167664670679, 12.500968749999993, 11.977664670658664, 12.349197530864195, 12.198292682926855], "value": 12.127181818181777, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.766490196078438, 11.542461538461541, 11.720292968749987, 11.766980392156869, 11.81220472440946, 11.811318897637785, 11.76482352941176, 12.000099999999977, 11.583880308880303, 11.58372586872586, 11.765568627450985, 11.766686274509823], "value": 11.765568627450985, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.432142857142857, 11.111638888888896, 11.432200000000012, 11.305734463276824, 11.369573863636354, 11.17477653631287, 11.241544943820239, 11.303220338983081, 11.173463687150853, 11.23620786516857, 10.932923497267751, 11.364602272727263], "value": 11.241544943820239, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.802549999999999, 6.5686, 6.53895, 6.699300000000001, 6.6857500000000005], "value": 6.627175, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4280.915, 4521.63, 4349.910000000001, 4635.07, 4600.695000000001], "value": 4561.1625, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
13:14:43 INFO - Return code: 0
13:14:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
13:14:43 INFO - # TBPL SUCCESS #
13:14:43 INFO - Running post-action listener: _package_coverage_data
13:14:43 INFO - Running post-action listener: _resource_record_post_action
13:14:43 INFO - [mozharness: 2017-11-17 21:14:43.649708Z] Finished run-tests step (success)
13:14:43 INFO - Running post-run listener: _resource_record_post_run
13:14:43 INFO - Total resource usage - Wall time: 630s; CPU: 37.0%; Read bytes: 6279168; Write bytes: 331051008; Read time: 436; Write time: 226632
13:14:43 INFO - TinderboxPrint: CPU usage
37.2%
13:14:43 INFO - TinderboxPrint: I/O read bytes / time
6,279,168 / 436
13:14:43 INFO - TinderboxPrint: I/O write bytes / time
331,051,008 / 226,632
13:14:43 INFO - TinderboxPrint: CPU idle
3,103.5 (62.8%)
13:14:43 INFO - TinderboxPrint: CPU system
308.5 (6.2%)
13:14:43 INFO - TinderboxPrint: CPU user
1,509.0 (30.5%)
13:14:43 INFO - TinderboxPrint: Swap in / out
0 / 0
13:14:43 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 4567040; Read time: 0; Write time: 524
13:14:43 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
13:14:43 INFO - run-tests - Wall time: 611s; CPU: 38.0%; Read bytes: 6279168; Write bytes: 324665344; Read time: 436; Write time: 225944
13:14:43 INFO - Running post-run listener: _upload_blobber_files
13:14:43 INFO - Blob upload gear active.
13:14:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
13:14:43 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
13:14:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
13:14:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
13:14:44 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
13:14:44 INFO - SNIMissingWarning
13:14: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
13:14:44 INFO - InsecurePlatformWarning
13:14:44 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.)
13:14:44 INFO - SubjectAltNameWarning
13:14:44 INFO - (blobuploader) - INFO - Open directory for files ...
13:14:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
13:14:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:14:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:14: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
13:14:44 INFO - InsecurePlatformWarning
13:14:44 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.)
13:14:44 INFO - SubjectAltNameWarning
13:14:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:14:45 INFO - InsecurePlatformWarning
13:14:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
13:14:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:14:45 INFO - (blobuploader) - INFO - Done attempting.
13:14:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
13:14:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:14:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:14:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:14:45 INFO - InsecurePlatformWarning
13:14:45 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.)
13:14:45 INFO - SubjectAltNameWarning
13:14:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:14:45 INFO - InsecurePlatformWarning
13:14:46 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
13:14:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:14:46 INFO - (blobuploader) - INFO - Done attempting.
13:14:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
13:14:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:14:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:14:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:14:46 INFO - InsecurePlatformWarning
13:14:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
13:14:46 INFO - SubjectAltNameWarning
13:14:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:14:46 INFO - InsecurePlatformWarning
13:14:47 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
13:14:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:14:47 INFO - (blobuploader) - INFO - Done attempting.
13:14:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
13:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:14:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:14:47 INFO - InsecurePlatformWarning
13:14:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
13:14:47 INFO - SubjectAltNameWarning
13:14:49 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
13:14:49 INFO - InsecurePlatformWarning
13:14:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
13:14:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:14:49 INFO - (blobuploader) - INFO - Done attempting.
13:14:49 INFO - (blobuploader) - INFO - Iteration through files over.
13:14:49 INFO - Return code: 0
13:14:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
13:14:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
13:14:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d0a6e5b15918f71924858f34d8f4f056f677ada79287ac04b8685a8ac64a61b420b0fed7916b5aa9072f450702137da122598ac1d332b98685fc656d6f9dc71", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bae33817aeaaead561a3d64e826a2f9a4a83d1f5346cd9f9e3c4fe0cd6ea17da5475514759505aa69940bd225d2fce0f38959345556cf15ae378e2f799aa5713", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1490834aa5a707a2d30e7453af3797f53786595a1fd8b363761340b69e3297a785ca5f0cf5f72b3150656a62f0c235480e0703740441a76e9b86553961cfb5e4", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1192841435d4a1ff9730bd83a06b27c50c7e818f0e8dd44858746c3538a95774ee371c76bfe12a6405c3b4de23a7c676527e1818e623f13a217362468dd4182"}
13:14:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
13:14:49 INFO - Writing to file /builds/slave/test/properties/blobber_files
13:14:49 INFO - Contents:
13:14:49 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d0a6e5b15918f71924858f34d8f4f056f677ada79287ac04b8685a8ac64a61b420b0fed7916b5aa9072f450702137da122598ac1d332b98685fc656d6f9dc71", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bae33817aeaaead561a3d64e826a2f9a4a83d1f5346cd9f9e3c4fe0cd6ea17da5475514759505aa69940bd225d2fce0f38959345556cf15ae378e2f799aa5713", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1490834aa5a707a2d30e7453af3797f53786595a1fd8b363761340b69e3297a785ca5f0cf5f72b3150656a62f0c235480e0703740441a76e9b86553961cfb5e4", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1192841435d4a1ff9730bd83a06b27c50c7e818f0e8dd44858746c3538a95774ee371c76bfe12a6405c3b4de23a7c676527e1818e623f13a217362468dd4182"}
13:14:49 INFO - Running post-run listener: copy_logs_to_upload_dir
13:14:49 INFO - Copying logs to upload dir...
13:14:49 INFO - mkdir: /builds/slave/test/build/upload/logs
13:14:49 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=689.126184
========= master_lag: 0.02 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 29 secs) (at 2017-11-17 13:14:49.673954) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-17 13:14:49.677687) =========
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=a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d0a6e5b15918f71924858f34d8f4f056f677ada79287ac04b8685a8ac64a61b420b0fed7916b5aa9072f450702137da122598ac1d332b98685fc656d6f9dc71", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bae33817aeaaead561a3d64e826a2f9a4a83d1f5346cd9f9e3c4fe0cd6ea17da5475514759505aa69940bd225d2fce0f38959345556cf15ae378e2f799aa5713", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1490834aa5a707a2d30e7453af3797f53786595a1fd8b363761340b69e3297a785ca5f0cf5f72b3150656a62f0c235480e0703740441a76e9b86553961cfb5e4", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1192841435d4a1ff9730bd83a06b27c50c7e818f0e8dd44858746c3538a95774ee371c76bfe12a6405c3b4de23a7c676527e1818e623f13a217362468dd4182"}
build_url:https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011435
build_url: 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3d0a6e5b15918f71924858f34d8f4f056f677ada79287ac04b8685a8ac64a61b420b0fed7916b5aa9072f450702137da122598ac1d332b98685fc656d6f9dc71", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bae33817aeaaead561a3d64e826a2f9a4a83d1f5346cd9f9e3c4fe0cd6ea17da5475514759505aa69940bd225d2fce0f38959345556cf15ae378e2f799aa5713", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1490834aa5a707a2d30e7453af3797f53786595a1fd8b363761340b69e3297a785ca5f0cf5f72b3150656a62f0c235480e0703740441a76e9b86553961cfb5e4", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1192841435d4a1ff9730bd83a06b27c50c7e818f0e8dd44858746c3538a95774ee371c76bfe12a6405c3b4de23a7c676527e1818e623f13a217362468dd4182"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-17 13:14:49.706786) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:14:49.707070) =========
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=a12cd81e0ef515edd9e0d800000001bb-1510952598.41128-581673163
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004554
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:14:49.790298) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-17 13:14:49.790616) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-17 13:14:49.790904) =========
========= Total master_lag: 0.19 =========