builder: mozilla-central_ubuntu64_hw_test-g4-e10s-pgo
slave: talos-linux64-ix-015
starttime: 1512248708.98
results: success (0)
revision: 44fad51c239833e710307b286b2b40953771fb96
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:08.977815) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:08.978351) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:08.978679) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:09.021774) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:09.022180) =========
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=20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-12-02 13:05:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 87.3M=0s
2017-12-02 13:05:09 (87.3 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.172852
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:09.252816) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:09.253173) =========
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=20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.082219
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:09.352490) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-02 13:05:09.352845) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 44fad51c239833e710307b286b2b40953771fb96 --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 44fad51c239833e710307b286b2b40953771fb96 --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=20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-12-02 13:05:09,402 truncating revision to first 12 chars
2017-12-02 13:05:09,402 Setting DEBUG logging.
2017-12-02 13:05:09,402 attempt 1/10
2017-12-02 13:05:09,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/44fad51c2398?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-12-02 13:05:10,080 unpacking tar archive at: mozilla-central-44fad51c2398/testing/mozharness/
program finished with exit code 0
elapsedTime=1.210346
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-02 13:05:10.582014) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:10.582436) =========
script_repo_revision: 44fad51c239833e710307b286b2b40953771fb96
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:10.582901) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:10.583204) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-02 13:05:10.598374) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 42 secs) (at 2017-12-02 13:05:10.598704) =========
/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=20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
13:05:10 INFO - MultiFileLogger online at 20171202 13:05:10 in /builds/slave/test
13:05:10 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:05:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
13:05:10 INFO - {'append_to_log': False,
13:05:10 INFO - 'base_work_dir': '/builds/slave/test',
13:05:10 INFO - 'blob_upload_branch': 'Firefox',
13:05:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
13:05:10 INFO - 'branch': 'Firefox',
13:05:10 INFO - 'buildbot_json_path': 'buildprops.json',
13:05:10 INFO - 'code_coverage': False,
13:05:10 INFO - 'config_files': ('talos/linux_config.py',),
13:05:10 INFO - 'default_actions': ('clobber',
13:05:10 INFO - 'read-buildbot-config',
13:05:10 INFO - 'download-and-extract',
13:05:10 INFO - 'populate-webroot',
13:05:10 INFO - 'create-virtualenv',
13:05:10 INFO - 'install',
13:05:10 INFO - 'setup-mitmproxy',
13:05:10 INFO - 'run-tests'),
13:05:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
13:05:10 INFO - 'disable_ccov_upload': False,
13:05:10 INFO - 'disable_stylo': False,
13:05:10 INFO - 'download_minidump_stackwalk': True,
13:05:10 INFO - 'download_symbols': 'ondemand',
13:05:10 INFO - 'e10s': False,
13:05:10 INFO - 'enable_stylo': False,
13:05:10 INFO - 'enable_webrender': False,
13:05:10 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
13:05:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
13:05:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
13:05:10 INFO - 'gecko_profile': False,
13:05:10 INFO - 'gecko_profile_interval': 0,
13:05:10 INFO - 'installer_path': 'installer.exe',
13:05:10 INFO - 'jsd_code_coverage': False,
13:05:10 INFO - 'log_level': 'info',
13:05:10 INFO - 'log_name': 'talos',
13:05:10 INFO - 'log_to_console': True,
13:05:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
13:05:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
13:05:10 INFO - 'opt_config_files': (),
13:05:10 INFO - 'pip_index': False,
13:05:10 INFO - 'suite': 'g4-e10s',
13:05:10 INFO - 'system_bits': '32',
13:05:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
13:05:10 INFO - 'title': 'talos-linux64-ix-015',
13:05:10 INFO - 'tooltool_cache': '/builds/tooltool_cache',
13:05:10 INFO - 'use_talos_json': True,
13:05:10 INFO - 'verify': 'False',
13:05:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
13:05:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
13:05:10 INFO - 'work_dir': 'build'}
13:05:10 INFO - [mozharness: 2017-12-02 21:05:10.879451Z] Running clobber step.
13:05:10 INFO - Running pre-action listener: _resource_record_pre_action
13:05:10 INFO - Running main action method: clobber
13:05:10 INFO - rmtree: /builds/slave/test/build
13:05:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
13:05:12 INFO - Running post-action listener: _resource_record_post_action
13:05:12 INFO - [mozharness: 2017-12-02 21:05:12.979957Z] Finished clobber step (success)
13:05:12 INFO - [mozharness: 2017-12-02 21:05:12.980084Z] Running read-buildbot-config step.
13:05:12 INFO - Running pre-action listener: _resource_record_pre_action
13:05:12 INFO - Running main action method: read_buildbot_config
13:05:12 INFO - Using buildbot properties:
13:05:12 INFO - {
13:05:12 INFO - "project": "",
13:05:12 INFO - "product": "firefox",
13:05:12 INFO - "who": "ffxbld@noreply.mozilla.org",
13:05:12 INFO - "installer_path": "public/build/target.tar.bz2",
13:05:12 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
13:05:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
13:05:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-e10s",
13:05:12 INFO - "stage_platform": "linux64-pgo",
13:05:12 INFO - "basedir": "/builds/slave/test",
13:05:12 INFO - "buildnumber": 156,
13:05:12 INFO - "platform": "ubuntu64_hw",
13:05:12 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
13:05:12 INFO - "slavebuilddir": "test",
13:05:12 INFO - "taskId": "dZvwm_UkTEyWRaGdktDwag",
13:05:12 INFO - "branch": "mozilla-central",
13:05:12 INFO - "script_repo_revision": "production",
13:05:12 INFO - "revision": "44fad51c239833e710307b286b2b40953771fb96",
13:05:12 INFO - "slavename": "talos-linux64-ix-015",
13:05:12 INFO - "repo_path": "mozilla-central"
13:05:12 INFO - }
13:05:12 INFO - Finding installer, test and symbols from parent task.
13:05:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZvwm_UkTEyWRaGdktDwag'}, attempt #1
13:05:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dZvwm_UkTEyWRaGdktDwag'}, attempt #1
13:05:13 INFO - Task dependencies: ZkkQmAVxSLi0rTbCwmZJgA
13:05:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA'}, attempt #1
13:05:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2
13:05:14 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.test_packages.json
13:05:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.crashreporter-symbols.zip
13:05:14 INFO - Running post-action listener: _resource_record_post_action
13:05:14 INFO - [mozharness: 2017-12-02 21:05:14.379797Z] Finished read-buildbot-config step (success)
13:05:14 INFO - [mozharness: 2017-12-02 21:05:14.380017Z] Running download-and-extract step.
13:05:14 INFO - Running pre-action listener: _resource_record_pre_action
13:05:14 INFO - Running main action method: download_and_extract
13:05:14 INFO - mkdir: /builds/slave/test/build/tests
13:05:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:14 INFO - trying https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.test_packages.json
13:05:14 INFO - Downloading https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
13:05:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
13:05:14 INFO - Downloaded 989 bytes.
13:05:14 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
13:05:14 INFO - Using the following test package requirements:
13:05:14 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
13:05:14 INFO - u'common': [u'target.common.tests.zip'],
13:05:14 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
13:05:14 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
13:05:14 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
13:05:14 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
13:05:14 INFO - u'mozbase': [u'target.common.tests.zip'],
13:05:14 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
13:05:14 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
13:05:14 INFO - u'web-platform': [u'target.common.tests.zip',
13:05:14 INFO - u'target.web-platform.tests.tar.gz'],
13:05:14 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
13:05:14 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
13:05:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.common.tests.zip
13:05:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.common.tests.zip'}, attempt #1
13:05:14 INFO - Fetch https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.common.tests.zip into memory
13:05:16 INFO - Content-Length response header: 60056186
13:05:16 INFO - Bytes received: 60056186
13:05:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.talos.tests.zip
13:05:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
13:05:27 INFO - Fetch https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.talos.tests.zip into memory
13:05:27 INFO - Content-Length response header: 18753193
13:05:27 INFO - Bytes received: 18753193
13:05:28 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:05:28 INFO - trying https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2
13:05:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
13:05:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
13:05:29 INFO - Downloaded 63514673 bytes.
13:05:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2
13:05:29 INFO - mkdir: /builds/slave/test/properties
13:05:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
13:05:29 INFO - Writing to file /builds/slave/test/properties/build_url
13:05:29 INFO - Contents:
13:05:29 INFO - build_url:https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2
13:05:29 INFO - Running post-action listener: _resource_record_post_action
13:05:29 INFO - Running post-action listener: find_tests_for_verification
13:05:29 INFO - Running post-action listener: set_extra_try_arguments
13:05:29 INFO - [mozharness: 2017-12-02 21:05:29.711847Z] Finished download-and-extract step (success)
13:05:29 INFO - [mozharness: 2017-12-02 21:05:29.711939Z] Running populate-webroot step.
13:05:29 INFO - Running pre-action listener: _resource_record_pre_action
13:05:29 INFO - Running main action method: populate_webroot
13:05:29 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
13:05:29 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['tresize',
13:05:29 INFO - 'tcanvasmark']},
13:05:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
13:05:29 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['dromaeo_css',
13:05:29 INFO - 'kraken']},
13:05:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:05:29 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:05:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'tests': ['damp', 'tps']},
13:05:29 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['damp', 'tps']},
13:05:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
13:05:29 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['dromaeo_dom']},
13:05:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
13:05:29 INFO - 'glvideo',
13:05:29 INFO - 'displaylist_mutate']},
13:05:29 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['basic_compositor_video',
13:05:29 INFO - 'glvideo']},
13:05:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
13:05:29 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['ts_paint_webext',
13:05:29 INFO - 'tp5o_webext']},
13:05:29 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
13:05:29 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:05:29 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:05:29 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:05:29 INFO - 'talos_options': ['--mitmproxy',
13:05:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:05:29 INFO - '--firstNonBlankPaint'],
13:05:29 INFO - 'tests': ['tp6_google_heavy',
13:05:29 INFO - 'tp6_youtube_heavy',
13:05:29 INFO - 'tp6_amazon_heavy',
13:05:29 INFO - 'tp6_facebook_heavy']},
13:05:29 INFO - 'other-e10s': {'tests': ['a11yr',
13:05:29 INFO - 'ts_paint',
13:05:29 INFO - 'tpaint',
13:05:29 INFO - 'sessionrestore',
13:05:29 INFO - 'sessionrestore_many_windows',
13:05:29 INFO - 'sessionrestore_no_auto_restore',
13:05:29 INFO - 'tabpaint',
13:05:29 INFO - 'cpstartup']},
13:05:29 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['a11yr',
13:05:29 INFO - 'ts_paint',
13:05:29 INFO - 'tpaint',
13:05:29 INFO - 'sessionrestore',
13:05:29 INFO - 'sessionrestore_many_windows',
13:05:29 INFO - 'sessionrestore_no_auto_restore',
13:05:29 INFO - 'tabpaint',
13:05:29 INFO - 'cpstartup']},
13:05:29 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
13:05:29 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
13:05:29 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['perf_reftest_singletons']},
13:05:29 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['perf_reftest']},
13:05:29 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
13:05:29 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['speedometer']},
13:05:29 INFO - 'svgr-e10s': {'tests': ['tsvgx',
13:05:29 INFO - 'tsvgr_opacity',
13:05:29 INFO - 'tart',
13:05:29 INFO - 'tscrollx',
13:05:29 INFO - 'tsvg_static']},
13:05:29 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['tsvgx',
13:05:29 INFO - 'tsvgr_opacity',
13:05:29 INFO - 'tart',
13:05:29 INFO - 'tscrollx',
13:05:29 INFO - 'tsvg_static']},
13:05:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
13:05:29 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'talos_options': ['--disable-stylo'],
13:05:29 INFO - 'tests': ['tp5o']},
13:05:29 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:05:29 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:05:29 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:05:29 INFO - 'talos_options': ['--mitmproxy',
13:05:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:05:29 INFO - '--firstNonBlankPaint'],
13:05:29 INFO - 'tests': ['tp6_google',
13:05:29 INFO - 'tp6_youtube',
13:05:29 INFO - 'tp6_amazon',
13:05:29 INFO - 'tp6_facebook']},
13:05:29 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:05:29 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:05:29 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:05:29 INFO - 'talos_options': ['--disable-stylo',
13:05:29 INFO - '--mitmproxy',
13:05:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:05:29 INFO - '--firstNonBlankPaint'],
13:05:29 INFO - 'tests': ['tp6_google',
13:05:29 INFO - 'tp6_youtube',
13:05:29 INFO - 'tp6_amazon',
13:05:29 INFO - 'tp6_facebook']},
13:05:29 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:05:29 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:05:29 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:05:29 INFO - 'talos_options': ['--stylo-threads=1',
13:05:29 INFO - '--mitmproxy',
13:05:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:05:29 INFO - '--firstNonBlankPaint'],
13:05:29 INFO - 'tests': ['tp6_google',
13:05:29 INFO - 'tp6_youtube',
13:05:29 INFO - 'tp6_amazon',
13:05:29 INFO - 'tp6_facebook']},
13:05:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'talos_options': ['--xperf_path',
13:05:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:05:29 INFO - 'tests': ['tp5n']},
13:05:29 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:05:29 INFO - 'talos_options': ['--disable-stylo',
13:05:29 INFO - '--xperf_path',
13:05:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:05:29 INFO - 'tests': ['tp5n']}}}
13:05:29 INFO - Running post-action listener: _resource_record_post_action
13:05:29 INFO - [mozharness: 2017-12-02 21:05:29.730074Z] Finished populate-webroot step (success)
13:05:29 INFO - [mozharness: 2017-12-02 21:05:29.730166Z] Running create-virtualenv step.
13:05:29 INFO - Running pre-action listener: _resource_record_pre_action
13:05:29 INFO - Running main action method: create_virtualenv
13:05:29 INFO - Creating virtualenv /builds/slave/test/build/venv
13:05:29 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:05:29 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
13:05:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
13:05:31 INFO - Using real prefix '/usr'
13:05:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python
13:05:33 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
13:05:35 INFO - Installing setuptools, pip, wheel...done.
13:05:35 INFO - Return code: 0
13:05:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
13:05:35 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:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:35 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:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:35 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:35 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:05:35 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:05:35 INFO - Using env: {'DISPLAY': ':0',
13:05:35 INFO - 'HOME': '/home/cltbld',
13:05:35 INFO - 'LANG': 'en_US.UTF-8',
13:05:35 INFO - 'LANGUAGE': 'en_US:en',
13:05:35 INFO - 'LOGNAME': 'cltbld',
13:05:35 INFO - 'MAIL': '/var/mail/cltbld',
13:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:35 INFO - 'MOZ_NO_REMOTE': '1',
13:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:35 INFO - 'NO_EM_RESTART': '1',
13:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:35 INFO - 'PWD': '/builds/slave/test',
13:05:35 INFO - 'SHELL': '/bin/bash',
13:05:35 INFO - 'SHLVL': '1',
13:05:35 INFO - 'TERM': 'linux',
13:05:35 INFO - 'TMOUT': '86400',
13:05:35 INFO - 'USER': 'cltbld',
13:05:35 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:35 INFO - '_': '/tools/buildbot/bin/python'}
13:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:35 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
13:05:35 INFO - Return code: 0
13:05:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
13:05:35 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:05:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:35 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:05:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:35 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:35 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:05:35 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:05:35 INFO - Using env: {'DISPLAY': ':0',
13:05:35 INFO - 'HOME': '/home/cltbld',
13:05:35 INFO - 'LANG': 'en_US.UTF-8',
13:05:35 INFO - 'LANGUAGE': 'en_US:en',
13:05:35 INFO - 'LOGNAME': 'cltbld',
13:05:35 INFO - 'MAIL': '/var/mail/cltbld',
13:05:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:35 INFO - 'MOZ_NO_REMOTE': '1',
13:05:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:35 INFO - 'NO_EM_RESTART': '1',
13:05:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:35 INFO - 'PWD': '/builds/slave/test',
13:05:35 INFO - 'SHELL': '/bin/bash',
13:05:35 INFO - 'SHLVL': '1',
13:05:35 INFO - 'TERM': 'linux',
13:05:35 INFO - 'TMOUT': '86400',
13:05:35 INFO - 'USER': 'cltbld',
13:05:35 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:35 INFO - '_': '/tools/buildbot/bin/python'}
13:05:35 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:35 INFO - Collecting psutil>=3.1.1
13:05:37 INFO - Installing collected packages: psutil
13:05:37 INFO - Successfully installed psutil-3.1.1
13:05:37 INFO - Return code: 0
13:05:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
13:05: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:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05: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:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:37 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:37 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:05:37 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:05:37 INFO - Using env: {'DISPLAY': ':0',
13:05:37 INFO - 'HOME': '/home/cltbld',
13:05:37 INFO - 'LANG': 'en_US.UTF-8',
13:05:37 INFO - 'LANGUAGE': 'en_US:en',
13:05:37 INFO - 'LOGNAME': 'cltbld',
13:05:37 INFO - 'MAIL': '/var/mail/cltbld',
13:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:37 INFO - 'MOZ_NO_REMOTE': '1',
13:05:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:37 INFO - 'NO_EM_RESTART': '1',
13:05:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:37 INFO - 'PWD': '/builds/slave/test',
13:05:37 INFO - 'SHELL': '/bin/bash',
13:05:37 INFO - 'SHLVL': '1',
13:05:37 INFO - 'TERM': 'linux',
13:05:37 INFO - 'TMOUT': '86400',
13:05:37 INFO - 'USER': 'cltbld',
13:05:37 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:37 INFO - '_': '/tools/buildbot/bin/python'}
13:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:37 INFO - Collecting mozsystemmonitor==0.3
13:05:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
13:05:38 INFO - Installing collected packages: mozsystemmonitor
13:05:38 INFO - Successfully installed mozsystemmonitor-0.3
13:05:38 INFO - Return code: 0
13:05:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
13:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:38 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:38 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:05:38 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:05:38 INFO - Using env: {'DISPLAY': ':0',
13:05:38 INFO - 'HOME': '/home/cltbld',
13:05:38 INFO - 'LANG': 'en_US.UTF-8',
13:05:38 INFO - 'LANGUAGE': 'en_US:en',
13:05:38 INFO - 'LOGNAME': 'cltbld',
13:05:38 INFO - 'MAIL': '/var/mail/cltbld',
13:05:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:38 INFO - 'MOZ_NO_REMOTE': '1',
13:05:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:38 INFO - 'NO_EM_RESTART': '1',
13:05:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:38 INFO - 'PWD': '/builds/slave/test',
13:05:38 INFO - 'SHELL': '/bin/bash',
13:05:38 INFO - 'SHLVL': '1',
13:05:38 INFO - 'TERM': 'linux',
13:05:38 INFO - 'TMOUT': '86400',
13:05:38 INFO - 'USER': 'cltbld',
13:05:38 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:38 INFO - '_': '/tools/buildbot/bin/python'}
13:05:39 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:39 INFO - Collecting jsonschema==2.5.1
13:05:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
13:05:40 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
13:05:41 INFO - Installing collected packages: functools32, jsonschema
13:05:41 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
13:05:41 INFO - Return code: 0
13:05:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
13:05:41 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:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:41 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:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:41 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:41 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:05:41 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:05:41 INFO - Using env: {'DISPLAY': ':0',
13:05:41 INFO - 'HOME': '/home/cltbld',
13:05:41 INFO - 'LANG': 'en_US.UTF-8',
13:05:41 INFO - 'LANGUAGE': 'en_US:en',
13:05:41 INFO - 'LOGNAME': 'cltbld',
13:05:41 INFO - 'MAIL': '/var/mail/cltbld',
13:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:41 INFO - 'MOZ_NO_REMOTE': '1',
13:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:41 INFO - 'NO_EM_RESTART': '1',
13:05:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:41 INFO - 'PWD': '/builds/slave/test',
13:05:41 INFO - 'SHELL': '/bin/bash',
13:05:41 INFO - 'SHLVL': '1',
13:05:41 INFO - 'TERM': 'linux',
13:05:41 INFO - 'TMOUT': '86400',
13:05:41 INFO - 'USER': 'cltbld',
13:05:41 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:41 INFO - '_': '/tools/buildbot/bin/python'}
13:05:41 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
13:05:41 INFO - Return code: 0
13:05:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
13:05:41 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:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:41 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:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:41 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:41 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:05:41 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:05:41 INFO - Using env: {'DISPLAY': ':0',
13:05:41 INFO - 'HOME': '/home/cltbld',
13:05:41 INFO - 'LANG': 'en_US.UTF-8',
13:05:41 INFO - 'LANGUAGE': 'en_US:en',
13:05:41 INFO - 'LOGNAME': 'cltbld',
13:05:41 INFO - 'MAIL': '/var/mail/cltbld',
13:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:41 INFO - 'MOZ_NO_REMOTE': '1',
13:05:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:41 INFO - 'NO_EM_RESTART': '1',
13:05:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:41 INFO - 'PWD': '/builds/slave/test',
13:05:41 INFO - 'SHELL': '/bin/bash',
13:05:41 INFO - 'SHLVL': '1',
13:05:41 INFO - 'TERM': 'linux',
13:05:41 INFO - 'TMOUT': '86400',
13:05:41 INFO - 'USER': 'cltbld',
13:05:41 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:41 INFO - '_': '/tools/buildbot/bin/python'}
13:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:42 INFO - Collecting blobuploader==1.2.4
13:05:43 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
13:05:45 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
13:05:46 INFO - Installing collected packages: requests, docopt, blobuploader
13:05:46 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
13:05:46 INFO - Return code: 0
13:05:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:05: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:05:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05: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:05:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:46 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:46 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:05:46 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:05:46 INFO - Using env: {'DISPLAY': ':0',
13:05:46 INFO - 'HOME': '/home/cltbld',
13:05:46 INFO - 'LANG': 'en_US.UTF-8',
13:05:46 INFO - 'LANGUAGE': 'en_US:en',
13:05:46 INFO - 'LOGNAME': 'cltbld',
13:05:46 INFO - 'MAIL': '/var/mail/cltbld',
13:05:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:46 INFO - 'MOZ_NO_REMOTE': '1',
13:05:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:46 INFO - 'NO_EM_RESTART': '1',
13:05:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:46 INFO - 'PWD': '/builds/slave/test',
13:05:46 INFO - 'SHELL': '/bin/bash',
13:05:46 INFO - 'SHLVL': '1',
13:05:46 INFO - 'TERM': 'linux',
13:05:46 INFO - 'TMOUT': '86400',
13:05:46 INFO - 'USER': 'cltbld',
13:05:46 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:46 INFO - '_': '/tools/buildbot/bin/python'}
13:05:46 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:46 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:05:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:05:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:05:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:05:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:05:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:05:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:05:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:05:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:05:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:05:48 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:05:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:05:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:05:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:05:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:05:49 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:05:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:05:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
13:05:50 INFO - Running setup.py install for manifestparser: started
13:05:50 INFO - Running setup.py install for manifestparser: finished with status 'done'
13:05:50 INFO - Running setup.py install for mozcrash: started
13:05:50 INFO - Running setup.py install for mozcrash: finished with status 'done'
13:05:50 INFO - Running setup.py install for mozdebug: started
13:05:50 INFO - Running setup.py install for mozdebug: finished with status 'done'
13:05:50 INFO - Running setup.py install for mozdevice: started
13:05:50 INFO - Running setup.py install for mozdevice: finished with status 'done'
13:05:51 INFO - Running setup.py install for mozfile: started
13:05:51 INFO - Running setup.py install for mozfile: finished with status 'done'
13:05:51 INFO - Running setup.py install for mozhttpd: started
13:05:51 INFO - Running setup.py install for mozhttpd: finished with status 'done'
13:05:51 INFO - Running setup.py install for mozinfo: started
13:05:51 INFO - Running setup.py install for mozinfo: finished with status 'done'
13:05:51 INFO - Running setup.py install for mozInstall: started
13:05:51 INFO - Running setup.py install for mozInstall: finished with status 'done'
13:05:51 INFO - Running setup.py install for mozleak: started
13:05:52 INFO - Running setup.py install for mozleak: finished with status 'done'
13:05:52 INFO - Running setup.py install for mozlog: started
13:05:52 INFO - Running setup.py install for mozlog: finished with status 'done'
13:05:52 INFO - Running setup.py install for moznetwork: started
13:05:52 INFO - Running setup.py install for moznetwork: finished with status 'done'
13:05:52 INFO - Running setup.py install for mozprocess: started
13:05:52 INFO - Running setup.py install for mozprocess: finished with status 'done'
13:05:52 INFO - Running setup.py install for mozprofile: started
13:05:52 INFO - Running setup.py install for mozprofile: finished with status 'done'
13:05:52 INFO - Running setup.py install for mozrunner: started
13:05:53 INFO - Running setup.py install for mozrunner: finished with status 'done'
13:05:53 INFO - Running setup.py install for mozscreenshot: started
13:05:53 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
13:05:53 INFO - Running setup.py install for moztest: started
13:05:53 INFO - Running setup.py install for moztest: finished with status 'done'
13:05:53 INFO - Running setup.py install for mozversion: started
13:05:53 INFO - Running setup.py install for mozversion: finished with status 'done'
13:05:54 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.14 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
13:05:54 INFO - Return code: 0
13:05:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:54 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:05:54 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:05:54 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:05:54 INFO - Using env: {'DISPLAY': ':0',
13:05:54 INFO - 'HOME': '/home/cltbld',
13:05:54 INFO - 'LANG': 'en_US.UTF-8',
13:05:54 INFO - 'LANGUAGE': 'en_US:en',
13:05:54 INFO - 'LOGNAME': 'cltbld',
13:05:54 INFO - 'MAIL': '/var/mail/cltbld',
13:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:54 INFO - 'MOZ_NO_REMOTE': '1',
13:05:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:54 INFO - 'NO_EM_RESTART': '1',
13:05:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:54 INFO - 'PWD': '/builds/slave/test',
13:05:54 INFO - 'SHELL': '/bin/bash',
13:05:54 INFO - 'SHLVL': '1',
13:05:54 INFO - 'TERM': 'linux',
13:05:54 INFO - 'TMOUT': '86400',
13:05:54 INFO - 'USER': 'cltbld',
13:05:54 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:54 INFO - '_': '/tools/buildbot/bin/python'}
13:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:54 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:05:54 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:05:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:05:55 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:05:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:05:55 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:05:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:05:55 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:05:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:05:55 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:05:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:05:55 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:05:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:05:56 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:05:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:05:56 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:05:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:05:56 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:05:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:05:56 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:05:56 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:05:56 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:05:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:05:57 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:05:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:05:57 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:05:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:05:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.14 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
13:05:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:05:57 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:05:57 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:05:57 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:05:57 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:05:58 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:05:58 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
13:05:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
13:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 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:05:59 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
13:06:00 INFO - Installing collected packages: six, blessings
13:06:00 INFO - Successfully installed blessings-1.6 six-1.10.0
13:06:00 INFO - Return code: 0
13:06:00 INFO - Done creating virtualenv /builds/slave/test/build/venv.
13:06:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:06:00 INFO - Reading from file tmpfile_stdout
13:06:00 INFO - Reading from file tmpfile_stderr
13:06:00 INFO - Current package versions:
13:06:00 INFO - blessings == 1.6
13:06:00 INFO - blobuploader == 1.2.4
13:06:00 INFO - docopt == 0.6.1
13:06:00 INFO - functools32 == 3.2.3.post2
13:06:00 INFO - jsonschema == 2.5.1
13:06:00 INFO - manifestparser == 1.2
13:06:00 INFO - mozInstall == 1.14
13:06:00 INFO - mozcrash == 1.0
13:06:00 INFO - mozdebug == 0.1
13:06:00 INFO - mozdevice == 0.51
13:06:00 INFO - mozfile == 1.2
13:06:00 INFO - mozhttpd == 0.7
13:06:00 INFO - mozinfo == 0.10
13:06:00 INFO - mozleak == 0.1
13:06:00 INFO - mozlog == 3.5
13:06:00 INFO - moznetwork == 0.27
13:06:00 INFO - mozprocess == 0.25
13:06:00 INFO - mozprofile == 0.29
13:06:00 INFO - mozrunner == 6.14
13:06:00 INFO - mozscreenshot == 0.1
13:06:00 INFO - mozsystemmonitor == 0.3
13:06:00 INFO - moztest == 0.8
13:06:00 INFO - mozversion == 1.4
13:06:00 INFO - psutil == 3.1.1
13:06:00 INFO - requests == 1.2.3
13:06:00 INFO - six == 1.10.0
13:06:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:06:00 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:06:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:06:00 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:06:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:06:00 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:06:00 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:06:00 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:06:00 INFO - Using env: {'DISPLAY': ':0',
13:06:00 INFO - 'HOME': '/home/cltbld',
13:06:00 INFO - 'LANG': 'en_US.UTF-8',
13:06:00 INFO - 'LANGUAGE': 'en_US:en',
13:06:00 INFO - 'LOGNAME': 'cltbld',
13:06:00 INFO - 'MAIL': '/var/mail/cltbld',
13:06:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:06:00 INFO - 'MOZ_NO_REMOTE': '1',
13:06:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:06:00 INFO - 'NO_EM_RESTART': '1',
13:06:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:06:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:06:00 INFO - 'PWD': '/builds/slave/test',
13:06:00 INFO - 'SHELL': '/bin/bash',
13:06:00 INFO - 'SHLVL': '1',
13:06:00 INFO - 'TERM': 'linux',
13:06:00 INFO - 'TMOUT': '86400',
13:06:00 INFO - 'USER': 'cltbld',
13:06:00 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:06:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:06:00 INFO - '_': '/tools/buildbot/bin/python'}
13:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple
13:06:01 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:06:01 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:06:01 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:06:01 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:06:01 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:06:01 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:06:01 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:06:01 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:06:01 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:06:01 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
13:06:02 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:06:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
13:06:03 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:06:03 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:06:03 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:06:03 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:06:04 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
13:06:04 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:06:05 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
13:06:05 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:06:06 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
13:06:06 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:06:07 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
13:06:07 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
13:06:08 INFO - Found existing installation: requests 1.2.3
13:06:08 INFO - Uninstalling requests-1.2.3:
13:06:08 INFO - Successfully uninstalled requests-1.2.3
13:06:08 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:06:08 INFO - Return code: 0
13:06:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
13:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:06:08 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 0x1c50e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cb5990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ddc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, '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': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622', '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:06:08 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:06:08 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:06:08 INFO - Using env: {'DISPLAY': ':0',
13:06:08 INFO - 'HOME': '/home/cltbld',
13:06:08 INFO - 'LANG': 'en_US.UTF-8',
13:06:08 INFO - 'LANGUAGE': 'en_US:en',
13:06:08 INFO - 'LOGNAME': 'cltbld',
13:06:08 INFO - 'MAIL': '/var/mail/cltbld',
13:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:06:08 INFO - 'MOZ_NO_REMOTE': '1',
13:06:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:06:08 INFO - 'NO_EM_RESTART': '1',
13:06:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:06:08 INFO - 'PWD': '/builds/slave/test',
13:06:08 INFO - 'SHELL': '/bin/bash',
13:06:08 INFO - 'SHLVL': '1',
13:06:08 INFO - 'TERM': 'linux',
13:06:08 INFO - 'TMOUT': '86400',
13:06:08 INFO - 'USER': 'cltbld',
13:06:08 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:06:08 INFO - '_': '/tools/buildbot/bin/python'}
13:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple
13:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
13:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
13:06:08 INFO - Return code: 0
13:06:08 INFO - Running post-action listener: _resource_record_post_action
13:06:08 INFO - Running post-action listener: _start_resource_monitoring
13:06:08 INFO - Starting resource monitoring.
13:06:08 INFO - [mozharness: 2017-12-02 21:06:08.735476Z] Finished create-virtualenv step (success)
13:06:08 INFO - [mozharness: 2017-12-02 21:06:08.735963Z] Running install step.
13:06:08 INFO - Running pre-action listener: _resource_record_pre_action
13:06:08 INFO - Running main action method: install
13:06:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:06:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:06:09 INFO - Reading from file tmpfile_stdout
13:06:09 INFO - Reading from file tmpfile_stderr
13:06:09 INFO - Detecting whether we're running mozinstall >=1.0...
13:06:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
13:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
13:06:09 INFO - Reading from file tmpfile_stdout
13:06:09 INFO - Output received:
13:06:09 INFO - Usage: mozinstall [options] installer
13:06:09 INFO - Options:
13:06:09 INFO - -h, --help show this help message and exit
13:06:09 INFO - -d DEST, --destination=DEST
13:06:09 INFO - Directory to install application into. [default:
13:06:09 INFO - "/builds/slave/test"]
13:06:09 INFO - --app=APP Application being installed. [default: firefox]
13:06:09 INFO - mkdir: /builds/slave/test/build/application
13:06:09 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:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
13:06:27 INFO - Reading from file tmpfile_stdout
13:06:27 INFO - Output received:
13:06:27 INFO - /builds/slave/test/build/application/firefox/firefox
13:06:27 INFO - Running post-action listener: _resource_record_post_action
13:06:27 INFO - [mozharness: 2017-12-02 21:06:27.656718Z] Finished install step (success)
13:06:27 INFO - [mozharness: 2017-12-02 21:06:27.656860Z] Running setup-mitmproxy step.
13:06:27 INFO - Running pre-action listener: _resource_record_pre_action
13:06:27 INFO - Running main action method: setup_mitmproxy
13:06:27 INFO - Skipping: mitmproxy is not required
13:06:27 INFO - Running post-action listener: _resource_record_post_action
13:06:27 INFO - [mozharness: 2017-12-02 21:06:27.657382Z] Finished setup-mitmproxy step (success)
13:06:27 INFO - [mozharness: 2017-12-02 21:06:27.657485Z] Running run-tests step.
13:06:27 INFO - Running pre-action listener: _resource_record_pre_action
13:06:27 INFO - Running pre-action listener: _set_gcov_prefix
13:06:27 INFO - Running main action method: run_tests
13:06:27 WARNING - Try message not found.
13:06:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
13:06:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
13:06:27 INFO - Python 2.7.3
13:06:27 INFO - Return code: 0
13:06:27 INFO - grabbing minidump binary from tooltool
13:06:27 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:06:27 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 0x1dd9b40>, '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 0x1ddaa40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ddaed0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
13:06:27 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:06:27 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:06:27 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:06:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
13:06:27 INFO - Return code: 0
13:06:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
13:06:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
13:06:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
13:06:27 INFO - ENV: RUST_BACKTRACE is now full
13:06:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
13:06:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
13:06:27 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/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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:06:27 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/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --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:06:27 INFO - Using env: {'DISPLAY': ':0',
13:06:27 INFO - 'HOME': '/home/cltbld',
13:06:27 INFO - 'LANG': 'en_US.UTF-8',
13:06:27 INFO - 'LANGUAGE': 'en_US:en',
13:06:27 INFO - 'LOGNAME': 'cltbld',
13:06:27 INFO - 'MAIL': '/var/mail/cltbld',
13:06:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:06:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:06:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:06:27 INFO - 'MOZ_NO_REMOTE': '1',
13:06:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:06:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:06:27 INFO - 'NO_EM_RESTART': '1',
13:06:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:06:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:06:27 INFO - 'PWD': '/builds/slave/test',
13:06:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:06:27 INFO - 'RUST_BACKTRACE': 'full',
13:06:27 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:06:27 INFO - 'SHELL': '/bin/bash',
13:06:27 INFO - 'SHLVL': '1',
13:06:27 INFO - 'TERM': 'linux',
13:06:27 INFO - 'TMOUT': '86400',
13:06:27 INFO - 'USER': 'cltbld',
13:06:27 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:06:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:06:27 INFO - '_': '/tools/buildbot/bin/python'}
13:06:27 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/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--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:06:28 INFO - mozversion application_buildid: 20171202194511
13:06:28 INFO - mozversion application_changeset: 44fad51c239833e710307b286b2b40953771fb96
13:06:28 INFO - mozversion application_display_name: Nightly
13:06:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
13:06:28 INFO - mozversion application_name: Firefox
13:06:28 INFO - mozversion application_remotingname: firefox
13:06:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
13:06:28 INFO - mozversion application_vendor: Mozilla
13:06:28 INFO - mozversion application_version: 59.0a1
13:06:28 INFO - mozversion platform_buildid: 20171202194511
13:06:28 INFO - mozversion platform_changeset: 44fad51c239833e710307b286b2b40953771fb96
13:06:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
13:06:28 INFO - mozversion platform_version: 59.0a1
13:06:28 INFO - using testdate: 1512248788
13:06:28 INFO - actual date: 1512248788
13:06:28 INFO - starting webserver on 'localhost:49462'
13:06:28 INFO - SUITE-START | Running 3 tests
13:06:28 INFO - TEST-START | basic_compositor_video
13:06:28 INFO - Initialising browser for basic_compositor_video test...
13:06:28 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:06:28 INFO - Installing Add-ons
13:06:28 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49462/getInfo.html -profile /tmp/tmp9HbkY6/profile
13:06:28 INFO - TEST-INFO | started process 3743 (/builds/slave/test/build/application/firefox/firefox http://localhost:49462/getInfo.html)
13:06:39 INFO - TEST-INFO | 3743: exit 0
13:06:39 INFO - Browser initialized.
13:06:39 INFO - Running cycle 1/1 for basic_compositor_video test...
13:06:39 INFO - Using env: {'DISPLAY': ':0',
13:06:39 INFO - 'HOME': '/home/cltbld',
13:06:39 INFO - 'JSGC_DISABLE_POISONING': '1',
13:06:39 INFO - 'LANG': 'en_US.UTF-8',
13:06:39 INFO - 'LANGUAGE': 'en_US:en',
13:06:39 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:06:39 INFO - 'LOGNAME': 'cltbld',
13:06:39 INFO - 'MAIL': '/var/mail/cltbld',
13:06:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:06:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:06:39 INFO - 'MOZ_CRASHREPORTER': '1',
13:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:06:39 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:06:39 INFO - 'MOZ_NO_REMOTE': '1',
13:06:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:06:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:06:39 INFO - 'NO_EM_RESTART': '1',
13:06:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:06:39 INFO - 'PWD': '/builds/slave/test',
13:06:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:06:39 INFO - 'RUST_BACKTRACE': 'full',
13:06:39 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:06:39 INFO - 'SHELL': '/bin/bash',
13:06:39 INFO - 'SHLVL': '1',
13:06:39 INFO - 'TERM': 'linux',
13:06:39 INFO - 'TMOUT': '86400',
13:06:39 INFO - 'USER': 'cltbld',
13:06:39 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:06:39 INFO - '_': '/tools/buildbot/bin/python'}
13:06:39 INFO - TEST-INFO | started process 4063 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9HbkY6/profile)
13:06:40 INFO - PID 4063 |
13:06:40 INFO - PID 4063 | (/builds/slave/test/build/application/firefox/firefox:4115): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:06:40 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822057174887894 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687114261884908 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.756542740046838 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7202923473774727 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 3.953175230566536 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.09126789366053 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4336155606407317 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3683249158249158 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.525837937384903 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634098591549295 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.313384879725085 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 9.853004926108374 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.03273897058823 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.583941798941789 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.553117154811714 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97883233532932 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.495 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05317679558011 ms/frame
13:07:26 INFO - PID 4063 |
13:07:26 INFO - PID 4063 | Cycle 1(1): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:08:12 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819843049327354 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683152627189324 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7680082498526808 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7244310344827596 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.167138888888889 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8186545801526726 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4333981693363853 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.339056761268777 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.54628465804066 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510137741046833 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.169915254237289 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 9.904702970297029 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.236573033707868 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.697887700534745 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450954356846474 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.837899408283999 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.4973563218391 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111361111111101 ms/frame
13:08:12 INFO - PID 4063 |
13:08:12 INFO - PID 4063 | Cycle 1(2): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:08:58 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838804713804716 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6666916666666665 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7434108076699593 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7308088235294121 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.049811066126855 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.141718426501033 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4297942857142854 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3394908180300495 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650762711864408 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572479108635099 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.384498269896202 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 9.852881773399007 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.281936090225575 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639069148936153 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500729166666648 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053885542168674 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.453740458015258 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.931366120218591 ms/frame
13:08:58 INFO - PID 4063 |
13:08:58 INFO - PID 4063 | Cycle 1(3): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:09:44 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6836728395061729 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6716123642439433 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7354106419895896 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7629603524229067 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.178885793871867 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.125525773195875 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.448528735632183 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3677693602693632 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5881191806331465 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.481260273972603 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 9.90145214521452 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 10.002649999999994 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.155037174721196 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.696336898395703 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.19526422764229 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.628284883720902 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674708171206232 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991510989011 ms/frame
13:09:44 INFO - PID 4063 |
13:09:44 INFO - PID 4063 | Cycle 1(4): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:10:31 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822169282511212 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6658368026644463 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.734682080924856 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6950974576271187 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9377493438320217 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158555093555092 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4568605990783414 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.390161016949152 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704781368821293 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.496648351648348 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.069530201342285 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 10.20573979591837 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.155762081784406 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.934754098360642 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348209876543201 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05295180722892 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.765843137254905 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992692307692298 ms/frame
13:10:31 INFO - PID 4063 |
13:10:31 INFO - PID 4063 | Cycle 1(5): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:11:17 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.678869613878008 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669250000000004 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7546228070175443 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7259663503019849 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.16148404993065 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.585008960573479 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4934284051222346 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4145477815699645 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.705304182509502 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.527720994475148 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.529140350877194 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 9.75587804878048 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.279887218045115 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.471910994764409 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399855371900824 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.346296296296288 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674902723735396 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.238932584269657 ms/frame
13:11:17 INFO - PID 4063 |
13:11:17 INFO - PID 4063 | Cycle 1(6): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:12:03 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6809943977591038 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668507089241034 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7274668969487625 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6995539507221742 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.161907073509013 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9608019801980197 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4413130733944963 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.36763468013468 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.671654064272213 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589301675977647 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.10466329966331 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 9.954800995024861 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.153736059479538 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.4723036649215 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397913223140488 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.765323529411784 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.628895348837199 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05046961325968 ms/frame
13:12:03 INFO - PID 4063 |
13:12:03 INFO - PID 4063 | Cycle 1(7): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:12:49 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680764705882353 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6667166666666666 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.726432681242808 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7089410760034167 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.133085399449034 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.13425619834711 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.6020648259303725 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.560151245551602 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683759469696972 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.393800539083554 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.275273972602744 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 10.002174999999989 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.071402214022148 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.816783783783762 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.45280082987552 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.195762195121926 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.279210526315804 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812783783783773 ms/frame
13:12:49 INFO - PID 4063 |
13:12:49 INFO - PID 4063 | Cycle 1(8): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:13:35 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822365470852019 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6718588137009192 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7488665501165503 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.705988917306053 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.197083916083915 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.073869653767821 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4060385925085144 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3573154362416093 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.57842007434944 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5275966850828775 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.384809688581324 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 10.05492462311558 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.11466666666665 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.530026315789483 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503145833333322 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.978203592814374 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.629883720930241 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.81337837837837 ms/frame
13:13:35 INFO - PID 4063 |
13:13:35 INFO - PID 4063 | Cycle 1(9): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:14:21 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6807955182072831 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673000000000002 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7488927738927742 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.736375868055555 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9530171277997366 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.993033932135729 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.584593787335723 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4483362068965513 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.736586998087955 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.55698611111112 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.138091216216216 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 10.312345360824748 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.27860902255638 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.582962962962965 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.24736734693879 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.769882352941162 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.279586466165396 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.993351648351654 ms/frame
13:14:21 INFO - PID 4063 |
13:14:21 INFO - PID 4063 | Cycle 1(10): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:15:07 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6782997762863536 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6697287145242066 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7488286713286711 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7732934397163125 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071594301221168 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.193930817610061 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.4534522439585724 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3791469594594625 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.639661654135337 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.556638888888887 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.001666666666667 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 9.803872549019616 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.113851851851845 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.756370967741962 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.396776859504119 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.909940476190492 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.32077358490565 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.700668449197869 ms/frame
13:15:07 INFO - PID 4063 |
13:15:07 INFO - PID 4063 | Cycle 1(11): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:15:54 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_startup = 1.678313758389262 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683736447039201 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7436664729808249 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7067491467576787 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8720258064516124 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158180873180871 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_startup = 3.44951724137931 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3788260135135126 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.661735849056606 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.511528925619839 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_startup = 10.239880546075085 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.480p.60fps.webm_scale_2_inclip = 9.951791044776108 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.197481343283593 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.697673796791426 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.349362139917682 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907529761904785 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.364943181818186 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991758241758241 ms/frame
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | Cycle 1(12): loaded http://localhost:49462/tests/video/video_playback.html (next: http://localhost:49462/tests/video/video_playback.html)
13:15:54 INFO - PID 4063 | __start_tp_report
13:15:54 INFO - PID 4063 | _x_x_mozilla_page_load
13:15:54 INFO - PID 4063 | _x_x_mozilla_page_load_details
13:15:54 INFO - PID 4063 | |i|pagename|runs|
13:15:54 INFO - PID 4063 | |0;240p.120fps.mp4_scale_1_startup;1.6822057174887894;1.6819843049327354;1.6838804713804716;1.6836728395061729;1.6822169282511212;1.678869613878008;1.6809943977591038;1.680764705882353;1.6822365470852019;1.6807955182072831;1.6782997762863536;1.678313758389262
13:15:54 INFO - PID 4063 | |1;240p.120fps.mp4_scale_1_inclip;1.6687114261884908;1.6683152627189324;1.6666916666666665;1.6716123642439433;1.6658368026644463;1.6669250000000004;1.668507089241034;1.6667166666666666;1.6718588137009192;1.6673000000000002;1.6697287145242066;1.6683736447039201
13:15:54 INFO - PID 4063 | |2;240p.120fps.mp4_scale_1.1_startup;1.756542740046838;1.7680082498526808;1.7434108076699593;1.7354106419895896;1.734682080924856;1.7546228070175443;1.7274668969487625;1.726432681242808;1.7488665501165503;1.7488927738927742;1.7488286713286711;1.7436664729808249
13:15:54 INFO - PID 4063 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7202923473774727;1.7244310344827596;1.7308088235294121;1.7629603524229067;1.6950974576271187;1.7259663503019849;1.6995539507221742;1.7089410760034167;1.705988917306053;1.736375868055555;1.7732934397163125;1.7067491467576787
13:15:54 INFO - PID 4063 | |4;240p.120fps.mp4_scale_2_startup;3.953175230566536;4.167138888888889;4.049811066126855;4.178885793871867;3.9377493438320217;4.16148404993065;4.161907073509013;4.133085399449034;4.197083916083915;3.9530171277997366;4.071594301221168;3.8720258064516124
13:15:54 INFO - PID 4063 | |5;240p.120fps.mp4_scale_2_inclip;4.09126789366053;3.8186545801526726;4.141718426501033;4.125525773195875;4.158555093555092;3.585008960573479;3.9608019801980197;4.13425619834711;4.073869653767821;3.993033932135729;4.193930817610061;4.158180873180871
13:15:54 INFO - PID 4063 | |6;480p.60fps.webm_scale_1_startup;3.4336155606407317;3.4333981693363853;3.4297942857142854;3.448528735632183;3.4568605990783414;3.4934284051222346;3.4413130733944963;3.6020648259303725;3.4060385925085144;3.584593787335723;3.4534522439585724;3.44951724137931
13:15:54 INFO - PID 4063 | |7;480p.60fps.webm_scale_1_inclip;3.3683249158249158;3.339056761268777;3.3394908180300495;3.3677693602693632;3.390161016949152;3.4145477815699645;3.36763468013468;3.560151245551602;3.3573154362416093;3.4483362068965513;3.3791469594594625;3.3788260135135126
13:15:54 INFO - PID 4063 | |8;480p.60fps.webm_scale_1.1_startup;5.525837937384903;5.54628465804066;5.650762711864408;5.5881191806331465;5.704781368821293;5.705304182509502;5.671654064272213;5.683759469696972;5.57842007434944;5.736586998087955;5.639661654135337;5.661735849056606
13:15:54 INFO - PID 4063 | |9;480p.60fps.webm_scale_1.1_inclip;5.634098591549295;5.510137741046833;5.572479108635099;5.481260273972603;5.496648351648348;5.527720994475148;5.589301675977647;5.393800539083554;5.5275966850828775;5.55698611111112;5.556638888888887;5.511528925619839
13:15:54 INFO - PID 4063 | |10;480p.60fps.webm_scale_2_startup;10.313384879725085;10.169915254237289;10.384498269896202;9.90145214521452;10.069530201342285;10.529140350877194;10.10466329966331;10.275273972602744;10.384809688581324;10.138091216216216;10.001666666666667;10.239880546075085
13:15:54 INFO - PID 4063 | |11;480p.60fps.webm_scale_2_inclip;9.853004926108374;9.904702970297029;9.852881773399007;10.002649999999994;10.20573979591837;9.75587804878048;9.954800995024861;10.002174999999989;10.05492462311558;10.312345360824748;9.803872549019616;9.951791044776108
13:15:54 INFO - PID 4063 | |12;1080p.60fps.mp4_scale_1_startup;11.03273897058823;11.236573033707868;11.281936090225575;11.155037174721196;11.155762081784406;11.279887218045115;11.153736059479538;11.071402214022148;11.11466666666665;11.27860902255638;11.113851851851845;11.197481343283593
13:15:54 INFO - PID 4063 | |13;1080p.60fps.mp4_scale_1_inclip;10.583941798941789;10.697887700534745;10.639069148936153;10.696336898395703;10.934754098360642;10.471910994764409;10.4723036649215;10.816783783783762;10.530026315789483;10.582962962962965;10.756370967741962;10.697673796791426
13:15:54 INFO - PID 4063 | |14;1080p.60fps.mp4_scale_1.1_startup;12.553117154811714;12.450954356846474;12.500729166666648;12.19526422764229;12.348209876543201;12.399855371900824;12.397913223140488;12.45280082987552;12.503145833333322;12.24736734693879;12.396776859504119;12.349362139917682
13:15:54 INFO - PID 4063 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.97883233532932;11.837899408283999;12.053885542168674;11.628284883720902;12.05295180722892;12.346296296296288;11.765323529411784;12.195762195121926;11.978203592814374;11.769882352941162;11.909940476190492;11.907529761904785
13:15:54 INFO - PID 4063 | |16;1080p.60fps.mp4_scale_2_startup;11.495;11.4973563218391;11.453740458015258;11.674708171206232;11.765843137254905;11.674902723735396;11.628895348837199;11.279210526315804;11.629883720930241;11.279586466165396;11.32077358490565;11.364943181818186
13:15:54 INFO - PID 4063 | |17;1080p.60fps.mp4_scale_2_inclip;11.05317679558011;11.111361111111101;10.931366120218591;10.991510989011;10.992692307692298;11.238932584269657;11.05046961325968;10.812783783783773;10.81337837837837;10.993351648351654;10.700668449197869;10.991758241758241
13:15:54 INFO - PID 4063 | __end_tp_report
13:15:54 INFO - PID 4063 | __start_cc_report
13:15:54 INFO - PID 4063 | _x_x_mozilla_cycle_collect,550
13:15:54 INFO - PID 4063 | __end_cc_report
13:15:54 INFO - PID 4063 | __startTimestamp1512249354319__endTimestamp
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | ------- Summary: start -------
13:15:54 INFO - PID 4063 | Number of tests: 18
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.1%) stddev-sans-first:0.00
13:15:54 INFO - PID 4063 | 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:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#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:15:54 INFO - PID 4063 | 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:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.75 stddev:0.01 (0.7%) stddev-sans-first:0.01
13:15:54 INFO - PID 4063 | Values: 1.8 1.8 1.7 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.7
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.72 Median:1.73 stddev:0.02 (1.4%) stddev-sans-first:0.03
13:15:54 INFO - PID 4063 | Values: 1.7 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.8 1.7
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.07 Median:4.15 stddev:0.11 (2.7%) stddev-sans-first:0.11
13:15:54 INFO - PID 4063 | Values: 4.0 4.2 4.0 4.2 3.9 4.2 4.2 4.1 4.2 4.0 4.1 3.9
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.04 Median:4.13 stddev:0.18 (4.3%) stddev-sans-first:0.19
13:15:54 INFO - PID 4063 | Values: 4.1 3.8 4.1 4.1 4.2 3.6 4.0 4.1 4.1 4.0 4.2 4.2
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.47 Median:3.45 stddev:0.06 (1.8%) stddev-sans-first:0.06
13:15:54 INFO - PID 4063 | Values: 3.4 3.4 3.4 3.4 3.5 3.5 3.4 3.6 3.4 3.6 3.5 3.4
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.39 Median:3.38 stddev:0.06 (1.8%) stddev-sans-first:0.06
13:15:54 INFO - PID 4063 | Values: 3.4 3.3 3.3 3.4 3.4 3.4 3.4 3.6 3.4 3.4 3.4 3.4
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.64 Median:5.67 stddev:0.07 (1.2%) stddev-sans-first:0.06
13:15:54 INFO - PID 4063 | Values: 5.5 5.5 5.7 5.6 5.7 5.7 5.7 5.7 5.6 5.7 5.6 5.7
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.53 Median:5.54 stddev:0.06 (1.1%) stddev-sans-first:0.05
13:15:54 INFO - PID 4063 | Values: 5.6 5.5 5.6 5.5 5.5 5.5 5.6 5.4 5.5 5.6 5.6 5.5
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.21 Median:10.29 stddev:0.18 (1.7%) stddev-sans-first:0.19
13:15:54 INFO - PID 4063 | Values: 10.3 10.2 10.4 9.9 10.1 10.5 10.1 10.3 10.4 10.1 10.0 10.2
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.97 Median:9.83 stddev:0.16 (1.6%) stddev-sans-first:0.17
13:15:54 INFO - PID 4063 | Values: 9.9 9.9 9.9 10.0 10.2 9.8 10.0 10.0 10.1 10.3 9.8 10.0
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.17 Median:11.18 stddev:0.08 (0.7%) stddev-sans-first:0.07
13:15:54 INFO - PID 4063 | Values: 11.0 11.2 11.3 11.2 11.2 11.3 11.2 11.1 11.1 11.3 11.1 11.2
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.66 Median:10.70 stddev:0.14 (1.3%) stddev-sans-first:0.14
13:15:54 INFO - PID 4063 | Values: 10.6 10.7 10.6 10.7 10.9 10.5 10.5 10.8 10.5 10.6 10.8 10.7
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.40 Median:12.43 stddev:0.10 (0.8%) stddev-sans-first:0.10
13:15:54 INFO - PID 4063 | Values: 12.6 12.5 12.5 12.2 12.3 12.4 12.4 12.5 12.5 12.2 12.4 12.3
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.95 Median:11.98 stddev:0.20 (1.6%) stddev-sans-first:0.21
13:15:54 INFO - PID 4063 | Values: 12.0 11.8 12.1 11.6 12.1 12.3 11.8 12.2 12.0 11.8 11.9 11.9
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.51 Median:11.56 stddev:0.17 (1.5%) stddev-sans-first:0.18
13:15:54 INFO - PID 4063 | Values: 11.5 11.5 11.5 11.7 11.8 11.7 11.6 11.3 11.6 11.3 11.3 11.4
13:15:54 INFO - PID 4063 |
13:15:54 INFO - PID 4063 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.97 Median:10.99 stddev:0.14 (1.3%) stddev-sans-first:0.15
13:15:54 INFO - PID 4063 | Values: 11.1 11.1 10.9 11.0 11.0 11.2 11.1 10.8 10.8 11.0 10.7 11.0
13:15:54 INFO - PID 4063 | -------- Summary: end --------
13:15:54 INFO - PID 4063 |
13:15:54 INFO - TEST-INFO | 4063: exit 0
13:15:54 INFO - TEST-OK | basic_compositor_video | took 566747ms
13:15:54 INFO - TEST-START | glvideo
13:15:54 INFO - Initialising browser for glvideo test...
13:15:54 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:15:54 INFO - Installing Add-ons
13:15:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49462/getInfo.html -profile /tmp/tmpX4PRtL/profile
13:15:54 INFO - TEST-INFO | started process 5308 (/builds/slave/test/build/application/firefox/firefox http://localhost:49462/getInfo.html)
13:16:01 INFO - TEST-INFO | 5308: exit 0
13:16:01 INFO - Browser initialized.
13:16:01 INFO - Running cycle 1/1 for glvideo test...
13:16:01 INFO - Using env: {'DISPLAY': ':0',
13:16:01 INFO - 'HOME': '/home/cltbld',
13:16:01 INFO - 'JSGC_DISABLE_POISONING': '1',
13:16:01 INFO - 'LANG': 'en_US.UTF-8',
13:16:01 INFO - 'LANGUAGE': 'en_US:en',
13:16:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:16:01 INFO - 'LOGNAME': 'cltbld',
13:16:01 INFO - 'MAIL': '/var/mail/cltbld',
13:16:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:16:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:16:01 INFO - 'MOZ_CRASHREPORTER': '1',
13:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:16:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:16:01 INFO - 'MOZ_NO_REMOTE': '1',
13:16:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:16:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:16:01 INFO - 'NO_EM_RESTART': '1',
13:16:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:16:01 INFO - 'PWD': '/builds/slave/test',
13:16:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:16:01 INFO - 'RUST_BACKTRACE': 'full',
13:16:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:16:01 INFO - 'SHELL': '/bin/bash',
13:16:01 INFO - 'SHLVL': '1',
13:16:01 INFO - 'TERM': 'linux',
13:16:01 INFO - 'TMOUT': '86400',
13:16:01 INFO - 'USER': 'cltbld',
13:16:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:16:01 INFO - '_': '/tools/buildbot/bin/python'}
13:16:01 INFO - TEST-INFO | started process 5641 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpX4PRtL/profile)
13:16:02 INFO - PID 5641 |
13:16:02 INFO - PID 5641 | (/builds/slave/test/build/application/firefox/firefox:5693): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:16:02 INFO - PID 5641 |
13:16:03 INFO - PID 5641 | [talos glvideo result] Mean tick time across 100 ticks: 6.894450000000001 ms
13:16:03 INFO - PID 5641 | Cycle 1(1): loaded http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html)
13:16:05 INFO - PID 5641 | [talos glvideo result] Mean tick time across 100 ticks: 6.56965 ms
13:16:05 INFO - PID 5641 | Cycle 1(2): loaded http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html)
13:16:06 INFO - PID 5641 | [talos glvideo result] Mean tick time across 100 ticks: 6.56125 ms
13:16:06 INFO - PID 5641 | Cycle 1(3): loaded http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html)
13:16:07 INFO - PID 5641 | [talos glvideo result] Mean tick time across 100 ticks: 6.5884 ms
13:16:07 INFO - PID 5641 | Cycle 1(4): loaded http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html)
13:16:09 INFO - PID 5641 | [talos glvideo result] Mean tick time across 100 ticks: 6.5565 ms
13:16:09 INFO - PID 5641 | Cycle 1(5): loaded http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49462/tests/webgl/benchmarks/video/video_upload.html)
13:16:09 INFO - PID 5641 | __start_tp_report
13:16:09 INFO - PID 5641 | _x_x_mozilla_page_load
13:16:09 INFO - PID 5641 | _x_x_mozilla_page_load_details
13:16:09 INFO - PID 5641 | |i|pagename|runs|
13:16:09 INFO - PID 5641 | |0;Mean tick time across 100 ticks: ;6.894450000000001;6.56965;6.56125;6.5884;6.5565
13:16:09 INFO - PID 5641 | __end_tp_report
13:16:09 INFO - PID 5641 | __start_cc_report
13:16:09 INFO - PID 5641 | _x_x_mozilla_cycle_collect,284
13:16:09 INFO - PID 5641 | __end_cc_report
13:16:09 INFO - PID 5641 | __startTimestamp1512249369308__endTimestamp
13:16:09 INFO - PID 5641 |
13:16:09 INFO - PID 5641 | ------- Summary: start -------
13:16:09 INFO - PID 5641 | Number of tests: 1
13:16:09 INFO - PID 5641 |
13:16:09 INFO - PID 5641 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.63 Median:6.57 stddev:0.15 (2.2%) stddev-sans-first:0.01
13:16:09 INFO - PID 5641 | Values: 6.9 6.6 6.6 6.6 6.6
13:16:09 INFO - PID 5641 | -------- Summary: end --------
13:16:09 INFO - PID 5641 |
13:16:09 INFO - PID 5641 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
13:16:09 INFO - TEST-INFO | 5641: exit 0
13:16:09 INFO - TEST-OK | glvideo | took 15022ms
13:16:09 INFO - TEST-START | displaylist_mutate
13:16:09 INFO - Initialising browser for displaylist_mutate test...
13:16:09 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:16:09 INFO - Installing Add-ons
13:16:09 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49462/getInfo.html -profile /tmp/tmpIhvh_p/profile
13:16:09 INFO - TEST-INFO | started process 5870 (/builds/slave/test/build/application/firefox/firefox http://localhost:49462/getInfo.html)
13:16:16 INFO - TEST-INFO | 5870: exit 0
13:16:16 INFO - Browser initialized.
13:16:16 INFO - Running cycle 1/1 for displaylist_mutate test...
13:16:16 INFO - Using env: {'DISPLAY': ':0',
13:16:16 INFO - 'HOME': '/home/cltbld',
13:16:16 INFO - 'JSGC_DISABLE_POISONING': '1',
13:16:16 INFO - 'LANG': 'en_US.UTF-8',
13:16:16 INFO - 'LANGUAGE': 'en_US:en',
13:16:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:16:16 INFO - 'LOGNAME': 'cltbld',
13:16:16 INFO - 'MAIL': '/var/mail/cltbld',
13:16:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:16:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:16:16 INFO - 'MOZ_CRASHREPORTER': '1',
13:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:16:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:16:16 INFO - 'MOZ_NO_REMOTE': '1',
13:16:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:16:16 INFO - 'NO_EM_RESTART': '1',
13:16:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:16:16 INFO - 'PWD': '/builds/slave/test',
13:16:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:16:16 INFO - 'RUST_BACKTRACE': 'full',
13:16:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:16:16 INFO - 'SHELL': '/bin/bash',
13:16:16 INFO - 'SHLVL': '1',
13:16:16 INFO - 'TERM': 'linux',
13:16:16 INFO - 'TMOUT': '86400',
13:16:16 INFO - 'USER': 'cltbld',
13:16:16 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622',
13:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:16:16 INFO - '_': '/tools/buildbot/bin/python'}
13:16:16 INFO - TEST-INFO | started process 6148 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpIhvh_p/profile)
13:16:16 INFO - PID 6148 |
13:16:16 INFO - PID 6148 | (/builds/slave/test/build/application/firefox/firefox:6201): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:16:16 INFO - PID 6148 |
13:16:21 INFO - PID 6148 | Cycle 1(1): loaded http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html)
13:16:27 INFO - PID 6148 | Cycle 1(2): loaded http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html)
13:16:32 INFO - PID 6148 | Cycle 1(3): loaded http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html)
13:16:37 INFO - PID 6148 | Cycle 1(4): loaded http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html)
13:16:43 INFO - PID 6148 | Cycle 1(5): loaded http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49462/tests/layout/benchmarks/displaylist_mutate.html)
13:16:43 INFO - PID 6148 | __start_tp_report
13:16:43 INFO - PID 6148 | _x_x_mozilla_page_load
13:16:43 INFO - PID 6148 | _x_x_mozilla_page_load_details
13:16:43 INFO - PID 6148 | |i|pagename|runs|
13:16:43 INFO - PID 6148 | |0;/displaylist_mutate.html;4242.5;4411.400000000001;4626.525;4509.8;4652.625
13:16:43 INFO - PID 6148 | __end_tp_report
13:16:43 INFO - PID 6148 | __start_cc_report
13:16:43 INFO - PID 6148 | _x_x_mozilla_cycle_collect,237
13:16:43 INFO - PID 6148 | __end_cc_report
13:16:43 INFO - PID 6148 | __startTimestamp1512249403512__endTimestamp
13:16:43 INFO - PID 6148 |
13:16:43 INFO - PID 6148 | ------- Summary: start -------
13:16:43 INFO - PID 6148 | Number of tests: 1
13:16:43 INFO - PID 6148 |
13:16:43 INFO - PID 6148 | [#0] /displaylist_mutate.html Cycles:5 Average:4488.57 Median:4509.80 stddev:168.00 (3.7%) stddev-sans-first:111.38
13:16:43 INFO - PID 6148 | Values: 4242.5 4411.4 4626.5 4509.8 4652.6
13:16:43 INFO - PID 6148 | -------- Summary: end --------
13:16:43 INFO - PID 6148 |
13:16:43 INFO - PID 6148 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
13:16:44 INFO - TEST-INFO | 6148: exit 0
13:16:44 INFO - TEST-OK | displaylist_mutate | took 34224ms
13:16:44 INFO - SUITE-END | took 615s
13:16:44 INFO - Completed test suite (00:10:16)
13:16:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.581979323717533, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6822057174887894, 1.6819843049327354, 1.6838804713804716, 1.6836728395061729, 1.6822169282511212, 1.678869613878008, 1.6809943977591038, 1.680764705882353, 1.6822365470852019, 1.6807955182072831, 1.6782997762863536, 1.678313758389262], "value": 1.6809943977591038, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6687114261884908, 1.6683152627189324, 1.6666916666666665, 1.6716123642439433, 1.6658368026644463, 1.6669250000000004, 1.668507089241034, 1.6667166666666666, 1.6718588137009192, 1.6673000000000002, 1.6697287145242066, 1.6683736447039201], "value": 1.6683152627189324, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.756542740046838, 1.7680082498526808, 1.7434108076699593, 1.7354106419895896, 1.734682080924856, 1.7546228070175443, 1.7274668969487625, 1.726432681242808, 1.7488665501165503, 1.7488927738927742, 1.7488286713286711, 1.7436664729808249], "value": 1.7436664729808249, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7202923473774727, 1.7244310344827596, 1.7308088235294121, 1.7629603524229067, 1.6950974576271187, 1.7259663503019849, 1.6995539507221742, 1.7089410760034167, 1.705988917306053, 1.736375868055555, 1.7732934397163125, 1.7067491467576787], "value": 1.7244310344827596, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.953175230566536, 4.167138888888889, 4.049811066126855, 4.178885793871867, 3.9377493438320217, 4.16148404993065, 4.161907073509013, 4.133085399449034, 4.197083916083915, 3.9530171277997366, 4.071594301221168, 3.8720258064516124], "value": 4.133085399449034, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.09126789366053, 3.8186545801526726, 4.141718426501033, 4.125525773195875, 4.158555093555092, 3.585008960573479, 3.9608019801980197, 4.13425619834711, 4.073869653767821, 3.993033932135729, 4.193930817610061, 4.158180873180871], "value": 4.125525773195875, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4336155606407317, 3.4333981693363853, 3.4297942857142854, 3.448528735632183, 3.4568605990783414, 3.4934284051222346, 3.4413130733944963, 3.6020648259303725, 3.4060385925085144, 3.584593787335723, 3.4534522439585724, 3.44951724137931], "value": 3.44951724137931, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.3683249158249158, 3.339056761268777, 3.3394908180300495, 3.3677693602693632, 3.390161016949152, 3.4145477815699645, 3.36763468013468, 3.560151245551602, 3.3573154362416093, 3.4483362068965513, 3.3791469594594625, 3.3788260135135126], "value": 3.3788260135135126, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.525837937384903, 5.54628465804066, 5.650762711864408, 5.5881191806331465, 5.704781368821293, 5.705304182509502, 5.671654064272213, 5.683759469696972, 5.57842007434944, 5.736586998087955, 5.639661654135337, 5.661735849056606], "value": 5.661735849056606, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.634098591549295, 5.510137741046833, 5.572479108635099, 5.481260273972603, 5.496648351648348, 5.527720994475148, 5.589301675977647, 5.393800539083554, 5.5275966850828775, 5.55698611111112, 5.556638888888887, 5.511528925619839], "value": 5.5275966850828775, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.313384879725085, 10.169915254237289, 10.384498269896202, 9.90145214521452, 10.069530201342285, 10.529140350877194, 10.10466329966331, 10.275273972602744, 10.384809688581324, 10.138091216216216, 10.001666666666667, 10.239880546075085], "value": 10.169915254237289, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.853004926108374, 9.904702970297029, 9.852881773399007, 10.002649999999994, 10.20573979591837, 9.75587804878048, 9.954800995024861, 10.002174999999989, 10.05492462311558, 10.312345360824748, 9.803872549019616, 9.951791044776108], "value": 9.954800995024861, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.03273897058823, 11.236573033707868, 11.281936090225575, 11.155037174721196, 11.155762081784406, 11.279887218045115, 11.153736059479538, 11.071402214022148, 11.11466666666665, 11.27860902255638, 11.113851851851845, 11.197481343283593], "value": 11.155762081784406, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.583941798941789, 10.697887700534745, 10.639069148936153, 10.696336898395703, 10.934754098360642, 10.471910994764409, 10.4723036649215, 10.816783783783762, 10.530026315789483, 10.582962962962965, 10.756370967741962, 10.697673796791426], "value": 10.696336898395703, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.553117154811714, 12.450954356846474, 12.500729166666648, 12.19526422764229, 12.348209876543201, 12.399855371900824, 12.397913223140488, 12.45280082987552, 12.503145833333322, 12.24736734693879, 12.396776859504119, 12.349362139917682], "value": 12.397913223140488, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.97883233532932, 11.837899408283999, 12.053885542168674, 11.628284883720902, 12.05295180722892, 12.346296296296288, 11.765323529411784, 12.195762195121926, 11.978203592814374, 11.769882352941162, 11.909940476190492, 11.907529761904785], "value": 11.909940476190492, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.495, 11.4973563218391, 11.453740458015258, 11.674708171206232, 11.765843137254905, 11.674902723735396, 11.628895348837199, 11.279210526315804, 11.629883720930241, 11.279586466165396, 11.32077358490565, 11.364943181818186], "value": 11.4973563218391, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.05317679558011, 11.111361111111101, 10.931366120218591, 10.991510989011, 10.992692307692298, 11.238932584269657, 11.05046961325968, 10.812783783783773, 10.81337837837837, 10.993351648351654, 10.700668449197869, 10.991758241758241], "value": 10.991758241758241, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.894450000000001, 6.56965, 6.56125, 6.5884, 6.5565], "value": 6.56545, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4242.5, 4411.400000000001, 4626.525, 4509.8, 4652.625], "value": 4568.1625, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
13:16:44 INFO - Return code: 0
13:16:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
13:16:44 INFO - # TBPL SUCCESS #
13:16:44 INFO - Running post-action listener: _package_coverage_data
13:16:44 INFO - Running post-action listener: _resource_record_post_action
13:16:44 INFO - [mozharness: 2017-12-02 21:16:44.542361Z] Finished run-tests step (success)
13:16:44 INFO - Running post-run listener: _resource_record_post_run
13:16:44 INFO - Total resource usage - Wall time: 636s; CPU: 37.0%; Read bytes: 19406848; Write bytes: 336285696; Read time: 5672; Write time: 330280
13:16:44 INFO - TinderboxPrint: CPU usage
37.1%
13:16:44 INFO - TinderboxPrint: I/O read bytes / time
19,406,848 / 5,672
13:16:44 INFO - TinderboxPrint: I/O write bytes / time
336,285,696 / 330,280
13:16:44 INFO - TinderboxPrint: CPU idle
3,143.8 (62.9%)
13:16:44 INFO - TinderboxPrint: CPU system
309.3 (6.2%)
13:16:44 INFO - TinderboxPrint: CPU user
1,512.8 (30.3%)
13:16:44 INFO - TinderboxPrint: Swap in / out
0 / 0
13:16:44 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5775360; Read time: 0; Write time: 848
13:16:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
13:16:44 INFO - run-tests - Wall time: 617s; CPU: 38.0%; Read bytes: 17645568; Write bytes: 330510336; Read time: 5624; Write time: 329432
13:16:44 INFO - Running post-run listener: _upload_blobber_files
13:16:44 INFO - Blob upload gear active.
13:16:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
13:16:44 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:16:44 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:16: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:16:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:16:45 INFO - SNIMissingWarning
13:16: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:16:45 INFO - InsecurePlatformWarning
13:16: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:16:45 INFO - SubjectAltNameWarning
13:16:45 INFO - (blobuploader) - INFO - Open directory for files ...
13:16:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
13:16:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:16:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:16: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:16:45 INFO - InsecurePlatformWarning
13:16: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:16:45 INFO - SubjectAltNameWarning
13:16:50 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:16:50 INFO - InsecurePlatformWarning
13:16:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
13:16:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:16:50 INFO - (blobuploader) - INFO - Done attempting.
13:16:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
13:16:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:16:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:16:50 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:16:50 INFO - InsecurePlatformWarning
13:16:50 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:16:50 INFO - SubjectAltNameWarning
13:16:50 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:16:50 INFO - InsecurePlatformWarning
13:16:51 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
13:16:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:16:51 INFO - (blobuploader) - INFO - Done attempting.
13:16:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
13:16:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:16:51 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:16:51 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:16:51 INFO - InsecurePlatformWarning
13:16:51 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:16:51 INFO - SubjectAltNameWarning
13:16:51 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:16:51 INFO - InsecurePlatformWarning
13:16:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
13:16:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:16:52 INFO - (blobuploader) - INFO - Done attempting.
13:16:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
13:16:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:16:52 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:16:52 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:16:52 INFO - InsecurePlatformWarning
13:16:52 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:16:52 INFO - SubjectAltNameWarning
13:16:52 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:16:52 INFO - InsecurePlatformWarning
13:16:53 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
13:16:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:16:53 INFO - (blobuploader) - INFO - Done attempting.
13:16:53 INFO - (blobuploader) - INFO - Iteration through files over.
13:16:53 INFO - Return code: 0
13:16:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
13:16:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
13:16:53 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de867c8008f9b3d9ea09b8f612cf179578a4a1996ce4637498d9c27f98034ee79ad08482310c38cf4a292c91363f31daa196269aecb195049acbc02018bda041", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90090e81e3c58708ebe5be2a094895ce3028c0ee6a70d009ad8441246189c3189054d815920f5e8e97514ec962d45c156ea2105f5782acfd1652e939025f5272", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7460a24edb5be524000d8566c7a403896e56d8dfb1bcc45f798e792367a6f28267c2a4c2073925d1106bc32465cb29ac451acecc515f837e95f152d87fbd6236", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d655f7c9a8c53cc4676fb08d10909a033e3bb52acad6b8f5e29c5da9e6d730d7cb5bf569fd66fd852b40d010c319302464e843518cc9385f3952145f1a08886f"}
13:16:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
13:16:53 INFO - Writing to file /builds/slave/test/properties/blobber_files
13:16:53 INFO - Contents:
13:16:53 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de867c8008f9b3d9ea09b8f612cf179578a4a1996ce4637498d9c27f98034ee79ad08482310c38cf4a292c91363f31daa196269aecb195049acbc02018bda041", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90090e81e3c58708ebe5be2a094895ce3028c0ee6a70d009ad8441246189c3189054d815920f5e8e97514ec962d45c156ea2105f5782acfd1652e939025f5272", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7460a24edb5be524000d8566c7a403896e56d8dfb1bcc45f798e792367a6f28267c2a4c2073925d1106bc32465cb29ac451acecc515f837e95f152d87fbd6236", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d655f7c9a8c53cc4676fb08d10909a033e3bb52acad6b8f5e29c5da9e6d730d7cb5bf569fd66fd852b40d010c319302464e843518cc9385f3952145f1a08886f"}
13:16:53 INFO - Running post-run listener: copy_logs_to_upload_dir
13:16:53 INFO - Copying logs to upload dir...
13:16:53 INFO - mkdir: /builds/slave/test/build/upload/logs
13:16:53 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=702.514497
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 42 secs) (at 2017-12-02 13:16:53.153834) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-02 13:16:53.156945) =========
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=20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de867c8008f9b3d9ea09b8f612cf179578a4a1996ce4637498d9c27f98034ee79ad08482310c38cf4a292c91363f31daa196269aecb195049acbc02018bda041", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90090e81e3c58708ebe5be2a094895ce3028c0ee6a70d009ad8441246189c3189054d815920f5e8e97514ec962d45c156ea2105f5782acfd1652e939025f5272", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7460a24edb5be524000d8566c7a403896e56d8dfb1bcc45f798e792367a6f28267c2a4c2073925d1106bc32465cb29ac451acecc515f837e95f152d87fbd6236", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d655f7c9a8c53cc4676fb08d10909a033e3bb52acad6b8f5e29c5da9e6d730d7cb5bf569fd66fd852b40d010c319302464e843518cc9385f3952145f1a08886f"}
build_url:https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011396
build_url: 'https://queue.taskcluster.net/v1/task/ZkkQmAVxSLi0rTbCwmZJgA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de867c8008f9b3d9ea09b8f612cf179578a4a1996ce4637498d9c27f98034ee79ad08482310c38cf4a292c91363f31daa196269aecb195049acbc02018bda041", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90090e81e3c58708ebe5be2a094895ce3028c0ee6a70d009ad8441246189c3189054d815920f5e8e97514ec962d45c156ea2105f5782acfd1652e939025f5272", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7460a24edb5be524000d8566c7a403896e56d8dfb1bcc45f798e792367a6f28267c2a4c2073925d1106bc32465cb29ac451acecc515f837e95f152d87fbd6236", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d655f7c9a8c53cc4676fb08d10909a033e3bb52acad6b8f5e29c5da9e6d730d7cb5bf569fd66fd852b40d010c319302464e843518cc9385f3952145f1a08886f"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-02 13:16:53.191324) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-02 13:16:53.191996) =========
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=20e666a3d4cfbb71505a330e000001b4-1512248107.218733-245840622
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004488
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-02 13:16:53.272741) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-02 13:16:53.273073) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-02 13:16:53.273406) =========
========= Total master_lag: 0.23 =========