builder: mozilla-central_ubuntu64_hw_test-g4-e10s-pgo
slave: talos-linux64-ix-003
starttime: 1510015785.72
results: success (0)
revision: c2fe4b3b1b930b3e7fdb84eae44cec165394f322
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.723055) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.723543) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.723816) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.742191) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.742592) =========
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=7de08804244eb36560b44971000001c3-1510015784.830348-724474727
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-06 16:49:45-- 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-11-06 16:49:45 (87.3 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.089981
========= master_lag: 0.01 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.844892) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.845200) =========
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=7de08804244eb36560b44971000001c3-1510015784.830348-724474727
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020603
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.894540) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:45.895016) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c2fe4b3b1b930b3e7fdb84eae44cec165394f322 --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 c2fe4b3b1b930b3e7fdb84eae44cec165394f322 --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=7de08804244eb36560b44971000001c3-1510015784.830348-724474727
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-06 16:49:45,952 truncating revision to first 12 chars
2017-11-06 16:49:45,952 Setting DEBUG logging.
2017-11-06 16:49:45,952 attempt 1/10
2017-11-06 16:49:45,952 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c2fe4b3b1b93?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-06 16:49:46,341 unpacking tar archive at: mozilla-central-c2fe4b3b1b93/testing/mozharness/
program finished with exit code 0
elapsedTime=0.970629
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:46.883172) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:46.883504) =========
script_repo_revision: c2fe4b3b1b930b3e7fdb84eae44cec165394f322
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:46.883890) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:46.884153) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-06 16:49:46.899159) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 15 mins, 2 secs) (at 2017-11-06 16:49:46.899515) =========
/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=7de08804244eb36560b44971000001c3-1510015784.830348-724474727
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
16:49:47 INFO - MultiFileLogger online at 20171106 16:49:47 in /builds/slave/test
16:49:47 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
16:49:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
16:49:47 INFO - {'append_to_log': False,
16:49:47 INFO - 'base_work_dir': '/builds/slave/test',
16:49:47 INFO - 'blob_upload_branch': 'Firefox',
16:49:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
16:49:47 INFO - 'branch': 'Firefox',
16:49:47 INFO - 'buildbot_json_path': 'buildprops.json',
16:49:47 INFO - 'code_coverage': False,
16:49:47 INFO - 'config_files': ('talos/linux_config.py',),
16:49:47 INFO - 'default_actions': ('clobber',
16:49:47 INFO - 'read-buildbot-config',
16:49:47 INFO - 'download-and-extract',
16:49:47 INFO - 'populate-webroot',
16:49:47 INFO - 'create-virtualenv',
16:49:47 INFO - 'install',
16:49:47 INFO - 'setup-mitmproxy',
16:49:47 INFO - 'run-tests'),
16:49:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
16:49:47 INFO - 'disable_ccov_upload': False,
16:49:47 INFO - 'disable_stylo': False,
16:49:47 INFO - 'download_minidump_stackwalk': True,
16:49:47 INFO - 'download_symbols': 'ondemand',
16:49:47 INFO - 'e10s': False,
16:49:47 INFO - 'enable_stylo': False,
16:49:47 INFO - 'enable_webrender': False,
16:49:47 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
16:49:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
16:49:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
16:49:47 INFO - 'gecko_profile': False,
16:49:47 INFO - 'gecko_profile_interval': 0,
16:49:47 INFO - 'installer_path': 'installer.exe',
16:49:47 INFO - 'jsd_code_coverage': False,
16:49:47 INFO - 'log_level': 'info',
16:49:47 INFO - 'log_name': 'talos',
16:49:47 INFO - 'log_to_console': True,
16:49:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
16:49:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
16:49:47 INFO - 'opt_config_files': (),
16:49:47 INFO - 'pip_index': False,
16:49:47 INFO - 'suite': 'g4-e10s',
16:49:47 INFO - 'system_bits': '32',
16:49:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
16:49:47 INFO - 'title': 'talos-linux64-ix-003',
16:49:47 INFO - 'tooltool_cache': '/builds/tooltool_cache',
16:49:47 INFO - 'use_talos_json': True,
16:49:47 INFO - 'verify': 'False',
16:49:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
16:49:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
16:49:47 INFO - 'work_dir': 'build'}
16:49:47 INFO - [mozharness: 2017-11-07 00:49:47.093299Z] Running clobber step.
16:49:47 INFO - Running pre-action listener: _resource_record_pre_action
16:49:47 INFO - Running main action method: clobber
16:49:47 INFO - rmtree: /builds/slave/test/build
16:49:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
16:49:48 INFO - Running post-action listener: _resource_record_post_action
16:49:48 INFO - [mozharness: 2017-11-07 00:49:48.290384Z] Finished clobber step (success)
16:49:48 INFO - [mozharness: 2017-11-07 00:49:48.290481Z] Running read-buildbot-config step.
16:49:48 INFO - Running pre-action listener: _resource_record_pre_action
16:49:48 INFO - Running main action method: read_buildbot_config
16:49:48 INFO - Using buildbot properties:
16:49:48 INFO - {
16:49:48 INFO - "project": "",
16:49:48 INFO - "product": "firefox",
16:49:48 INFO - "who": "ccoroiu@mozilla.com",
16:49:48 INFO - "installer_path": "public/build/target.tar.bz2",
16:49:48 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
16:49:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
16:49:48 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-e10s",
16:49:48 INFO - "stage_platform": "linux64-pgo",
16:49:48 INFO - "basedir": "/builds/slave/test",
16:49:48 INFO - "buildnumber": 119,
16:49:48 INFO - "platform": "ubuntu64_hw",
16:49:48 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
16:49:48 INFO - "slavebuilddir": "test",
16:49:48 INFO - "taskId": "ckGDorluQa6PBb-kfz47hg",
16:49:48 INFO - "branch": "mozilla-central",
16:49:48 INFO - "script_repo_revision": "production",
16:49:48 INFO - "revision": "c2fe4b3b1b930b3e7fdb84eae44cec165394f322",
16:49:48 INFO - "slavename": "talos-linux64-ix-003",
16:49:48 INFO - "repo_path": "mozilla-central"
16:49:48 INFO - }
16:49:48 INFO - Finding installer, test and symbols from parent task.
16:49:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ckGDorluQa6PBb-kfz47hg'}, attempt #1
16:49:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ckGDorluQa6PBb-kfz47hg'}, attempt #1
16:49:49 INFO - Task dependencies: E1AZuogaSaekvfF4GFZP9w
16:49:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w'}, attempt #1
16:49:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2
16:49:49 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.test_packages.json
16:49:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.crashreporter-symbols.zip
16:49:49 INFO - Running post-action listener: _resource_record_post_action
16:49:49 INFO - [mozharness: 2017-11-07 00:49:49.638553Z] Finished read-buildbot-config step (success)
16:49:49 INFO - [mozharness: 2017-11-07 00:49:49.638777Z] Running download-and-extract step.
16:49:49 INFO - Running pre-action listener: _resource_record_pre_action
16:49:49 INFO - Running main action method: download_and_extract
16:49:49 INFO - mkdir: /builds/slave/test/build/tests
16:49:49 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')]}
16:49:49 INFO - trying https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.test_packages.json
16:49:49 INFO - Downloading https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
16:49:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
16:49:50 INFO - Downloaded 989 bytes.
16:49:50 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
16:49:50 INFO - Using the following test package requirements:
16:49:50 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
16:49:50 INFO - u'common': [u'target.common.tests.zip'],
16:49:50 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
16:49:50 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
16:49:50 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
16:49:50 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
16:49:50 INFO - u'mozbase': [u'target.common.tests.zip'],
16:49:50 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
16:49:50 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
16:49:50 INFO - u'web-platform': [u'target.common.tests.zip',
16:49:50 INFO - u'target.web-platform.tests.tar.gz'],
16:49:50 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
16:49:50 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
16:49:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.common.tests.zip
16:49:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.common.tests.zip'}, attempt #1
16:49:50 INFO - Fetch https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.common.tests.zip into memory
16:51:00 INFO - Content-Length response header: 58647418
16:51:00 INFO - Bytes received: 58647418
16:51:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.talos.tests.zip
16:51:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.talos.tests.zip'}, attempt #1
16:51:11 INFO - Fetch https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.talos.tests.zip into memory
16:51:34 INFO - Content-Length response header: 18771919
16:51:34 INFO - Bytes received: 18771919
16:51: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')]}
16:51:35 INFO - trying https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2
16:51:35 INFO - Downloading https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
16:51:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
16:52:27 INFO - Downloaded 63200300 bytes.
16:52:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2
16:52:27 INFO - mkdir: /builds/slave/test/properties
16:52:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
16:52:27 INFO - Writing to file /builds/slave/test/properties/build_url
16:52:27 INFO - Contents:
16:52:27 INFO - build_url:https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2
16:52:27 INFO - Running post-action listener: _resource_record_post_action
16:52:27 INFO - Running post-action listener: find_tests_for_verification
16:52:27 INFO - Running post-action listener: set_extra_try_arguments
16:52:27 INFO - [mozharness: 2017-11-07 00:52:27.373746Z] Finished download-and-extract step (success)
16:52:27 INFO - [mozharness: 2017-11-07 00:52:27.373972Z] Running populate-webroot step.
16:52:27 INFO - Running pre-action listener: _resource_record_pre_action
16:52:27 INFO - Running main action method: populate_webroot
16:52:27 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
16:52:27 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['tresize',
16:52:27 INFO - 'tcanvasmark']},
16:52:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
16:52:27 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['dromaeo_css',
16:52:27 INFO - 'kraken']},
16:52:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:52:27 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
16:52:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'tests': ['damp', 'tps']},
16:52:27 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['damp', 'tps']},
16:52:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
16:52:27 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['dromaeo_dom']},
16:52:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
16:52:27 INFO - 'glvideo',
16:52:27 INFO - 'displaylist_mutate']},
16:52:27 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['basic_compositor_video',
16:52:27 INFO - 'glvideo']},
16:52:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
16:52:27 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['ts_paint_webext',
16:52:27 INFO - 'tp5o_webext']},
16:52:27 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
16:52:27 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:52:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:52:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:52:27 INFO - 'talos_options': ['--mitmproxy',
16:52:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:52:27 INFO - '--firstNonBlankPaint'],
16:52:27 INFO - 'tests': ['tp6_google_heavy',
16:52:27 INFO - 'tp6_youtube_heavy',
16:52:27 INFO - 'tp6_amazon_heavy',
16:52:27 INFO - 'tp6_facebook_heavy']},
16:52:27 INFO - 'other-e10s': {'tests': ['a11yr',
16:52:27 INFO - 'ts_paint',
16:52:27 INFO - 'tpaint',
16:52:27 INFO - 'sessionrestore',
16:52:27 INFO - 'sessionrestore_many_windows',
16:52:27 INFO - 'sessionrestore_no_auto_restore',
16:52:27 INFO - 'tabpaint',
16:52:27 INFO - 'cpstartup']},
16:52:27 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['a11yr',
16:52:27 INFO - 'ts_paint',
16:52:27 INFO - 'tpaint',
16:52:27 INFO - 'sessionrestore',
16:52:27 INFO - 'sessionrestore_many_windows',
16:52:27 INFO - 'sessionrestore_no_auto_restore',
16:52:27 INFO - 'tabpaint',
16:52:27 INFO - 'cpstartup']},
16:52:27 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
16:52:27 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
16:52:27 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['perf_reftest_singletons']},
16:52:27 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['perf_reftest']},
16:52:27 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
16:52:27 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['speedometer']},
16:52:27 INFO - 'svgr-e10s': {'tests': ['tsvgx',
16:52:27 INFO - 'tsvgr_opacity',
16:52:27 INFO - 'tart',
16:52:27 INFO - 'tscrollx',
16:52:27 INFO - 'tsvg_static']},
16:52:27 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['tsvgx',
16:52:27 INFO - 'tsvgr_opacity',
16:52:27 INFO - 'tart',
16:52:27 INFO - 'tscrollx',
16:52:27 INFO - 'tsvg_static']},
16:52:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
16:52:27 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'talos_options': ['--disable-stylo'],
16:52:27 INFO - 'tests': ['tp5o']},
16:52:27 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:52:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:52:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:52:27 INFO - 'talos_options': ['--mitmproxy',
16:52:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:52:27 INFO - '--firstNonBlankPaint'],
16:52:27 INFO - 'tests': ['tp6_google',
16:52:27 INFO - 'tp6_youtube',
16:52:27 INFO - 'tp6_amazon',
16:52:27 INFO - 'tp6_facebook']},
16:52:27 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:52:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:52:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:52:27 INFO - 'talos_options': ['--disable-stylo',
16:52:27 INFO - '--mitmproxy',
16:52:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:52:27 INFO - '--firstNonBlankPaint'],
16:52:27 INFO - 'tests': ['tp6_google',
16:52:27 INFO - 'tp6_youtube',
16:52:27 INFO - 'tp6_amazon',
16:52:27 INFO - 'tp6_facebook']},
16:52:27 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
16:52:27 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
16:52:27 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
16:52:27 INFO - 'talos_options': ['--stylo-threads=1',
16:52:27 INFO - '--mitmproxy',
16:52:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
16:52:27 INFO - '--firstNonBlankPaint'],
16:52:27 INFO - 'tests': ['tp6_google',
16:52:27 INFO - 'tp6_youtube',
16:52:27 INFO - 'tp6_amazon',
16:52:27 INFO - 'tp6_facebook']},
16:52:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'talos_options': ['--xperf_path',
16:52:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:52:27 INFO - 'tests': ['tp5n']},
16:52:27 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
16:52:27 INFO - 'talos_options': ['--disable-stylo',
16:52:27 INFO - '--xperf_path',
16:52:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
16:52:27 INFO - 'tests': ['tp5n']}}}
16:52:27 INFO - Running post-action listener: _resource_record_post_action
16:52:27 INFO - [mozharness: 2017-11-07 00:52:27.396163Z] Finished populate-webroot step (success)
16:52:27 INFO - [mozharness: 2017-11-07 00:52:27.396267Z] Running create-virtualenv step.
16:52:27 INFO - Running pre-action listener: _resource_record_pre_action
16:52:27 INFO - Running main action method: create_virtualenv
16:52:27 INFO - Creating virtualenv /builds/slave/test/build/venv
16:52:27 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
16:52:27 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
16:52:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
16:52:27 INFO - Using real prefix '/usr'
16:52:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python
16:52:27 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
16:52:29 INFO - Installing setuptools, pip, wheel...done.
16:52:29 INFO - Return code: 0
16:52:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
16:52:29 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')]}
16:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52:29 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')]}
16:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52:29 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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52:29 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
16:52:29 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
16:52:29 INFO - Using env: {'DISPLAY': ':0',
16:52:29 INFO - 'HOME': '/home/cltbld',
16:52:29 INFO - 'LANG': 'en_US.UTF-8',
16:52:29 INFO - 'LANGUAGE': 'en_US:en',
16:52:29 INFO - 'LOGNAME': 'cltbld',
16:52:29 INFO - 'MAIL': '/var/mail/cltbld',
16:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:29 INFO - 'MOZ_NO_REMOTE': '1',
16:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:29 INFO - 'NO_EM_RESTART': '1',
16:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:29 INFO - 'PWD': '/builds/slave/test',
16:52:29 INFO - 'SHELL': '/bin/bash',
16:52:29 INFO - 'SHLVL': '1',
16:52:29 INFO - 'TERM': 'linux',
16:52:29 INFO - 'TMOUT': '86400',
16:52:29 INFO - 'USER': 'cltbld',
16:52:29 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:29 INFO - '_': '/tools/buildbot/bin/python'}
16:52:29 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:29 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
16:52:29 INFO - Return code: 0
16:52:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
16:52:29 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')]}
16:52:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52:29 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')]}
16:52:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52:29 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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52:29 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
16:52:29 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
16:52:29 INFO - Using env: {'DISPLAY': ':0',
16:52:29 INFO - 'HOME': '/home/cltbld',
16:52:29 INFO - 'LANG': 'en_US.UTF-8',
16:52:29 INFO - 'LANGUAGE': 'en_US:en',
16:52:29 INFO - 'LOGNAME': 'cltbld',
16:52:29 INFO - 'MAIL': '/var/mail/cltbld',
16:52:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:29 INFO - 'MOZ_NO_REMOTE': '1',
16:52:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:29 INFO - 'NO_EM_RESTART': '1',
16:52:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:29 INFO - 'PWD': '/builds/slave/test',
16:52:29 INFO - 'SHELL': '/bin/bash',
16:52:29 INFO - 'SHLVL': '1',
16:52:29 INFO - 'TERM': 'linux',
16:52:29 INFO - 'TMOUT': '86400',
16:52:29 INFO - 'USER': 'cltbld',
16:52:29 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:29 INFO - '_': '/tools/buildbot/bin/python'}
16:52:30 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:30 INFO - Collecting psutil>=3.1.1
16:52:31 INFO - Installing collected packages: psutil
16:52:31 INFO - Successfully installed psutil-3.1.1
16:52:31 INFO - Return code: 0
16:52:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
16:52:31 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')]}
16:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52:31 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')]}
16:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52:31 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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52:31 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
16:52:31 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
16:52:31 INFO - Using env: {'DISPLAY': ':0',
16:52:31 INFO - 'HOME': '/home/cltbld',
16:52:31 INFO - 'LANG': 'en_US.UTF-8',
16:52:31 INFO - 'LANGUAGE': 'en_US:en',
16:52:31 INFO - 'LOGNAME': 'cltbld',
16:52:31 INFO - 'MAIL': '/var/mail/cltbld',
16:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:31 INFO - 'MOZ_NO_REMOTE': '1',
16:52:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:31 INFO - 'NO_EM_RESTART': '1',
16:52:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:31 INFO - 'PWD': '/builds/slave/test',
16:52:31 INFO - 'SHELL': '/bin/bash',
16:52:31 INFO - 'SHLVL': '1',
16:52:31 INFO - 'TERM': 'linux',
16:52:31 INFO - 'TMOUT': '86400',
16:52:31 INFO - 'USER': 'cltbld',
16:52:31 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:31 INFO - '_': '/tools/buildbot/bin/python'}
16:52:31 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:31 INFO - Collecting mozsystemmonitor==0.3
16:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
16:52:32 INFO - Installing collected packages: mozsystemmonitor
16:52:32 INFO - Successfully installed mozsystemmonitor-0.3
16:52:32 INFO - Return code: 0
16:52:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
16:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:52:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:52:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52:32 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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52:32 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
16:52:32 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
16:52:32 INFO - Using env: {'DISPLAY': ':0',
16:52:32 INFO - 'HOME': '/home/cltbld',
16:52:32 INFO - 'LANG': 'en_US.UTF-8',
16:52:32 INFO - 'LANGUAGE': 'en_US:en',
16:52:32 INFO - 'LOGNAME': 'cltbld',
16:52:32 INFO - 'MAIL': '/var/mail/cltbld',
16:52:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:32 INFO - 'MOZ_NO_REMOTE': '1',
16:52:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:32 INFO - 'NO_EM_RESTART': '1',
16:52:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:32 INFO - 'PWD': '/builds/slave/test',
16:52:32 INFO - 'SHELL': '/bin/bash',
16:52:32 INFO - 'SHLVL': '1',
16:52:32 INFO - 'TERM': 'linux',
16:52:32 INFO - 'TMOUT': '86400',
16:52:32 INFO - 'USER': 'cltbld',
16:52:32 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:32 INFO - '_': '/tools/buildbot/bin/python'}
16:52:33 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:33 INFO - Collecting jsonschema==2.5.1
16:52:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
16:52:34 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
16:52:35 INFO - Installing collected packages: functools32, jsonschema
16:52:35 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
16:52:35 INFO - Return code: 0
16:52:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
16:52: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')]}
16:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52: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')]}
16:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52: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', '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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52: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', 'functools32==3.2.3-2'] in /builds/slave/test/build
16:52: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 functools32==3.2.3-2
16:52:35 INFO - Using env: {'DISPLAY': ':0',
16:52:35 INFO - 'HOME': '/home/cltbld',
16:52:35 INFO - 'LANG': 'en_US.UTF-8',
16:52:35 INFO - 'LANGUAGE': 'en_US:en',
16:52:35 INFO - 'LOGNAME': 'cltbld',
16:52:35 INFO - 'MAIL': '/var/mail/cltbld',
16:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:35 INFO - 'MOZ_NO_REMOTE': '1',
16:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:35 INFO - 'NO_EM_RESTART': '1',
16:52:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:35 INFO - 'PWD': '/builds/slave/test',
16:52:35 INFO - 'SHELL': '/bin/bash',
16:52:35 INFO - 'SHLVL': '1',
16:52:35 INFO - 'TERM': 'linux',
16:52:35 INFO - 'TMOUT': '86400',
16:52:35 INFO - 'USER': 'cltbld',
16:52:35 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:35 INFO - '_': '/tools/buildbot/bin/python'}
16:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
16:52:35 INFO - Return code: 0
16:52:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
16:52: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')]}
16:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52: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')]}
16:52:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52: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', '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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52: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', 'blobuploader==1.2.4'] in /builds/slave/test/build
16:52: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 blobuploader==1.2.4
16:52:35 INFO - Using env: {'DISPLAY': ':0',
16:52:35 INFO - 'HOME': '/home/cltbld',
16:52:35 INFO - 'LANG': 'en_US.UTF-8',
16:52:35 INFO - 'LANGUAGE': 'en_US:en',
16:52:35 INFO - 'LOGNAME': 'cltbld',
16:52:35 INFO - 'MAIL': '/var/mail/cltbld',
16:52:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:35 INFO - 'MOZ_NO_REMOTE': '1',
16:52:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:35 INFO - 'NO_EM_RESTART': '1',
16:52:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:35 INFO - 'PWD': '/builds/slave/test',
16:52:35 INFO - 'SHELL': '/bin/bash',
16:52:35 INFO - 'SHLVL': '1',
16:52:35 INFO - 'TERM': 'linux',
16:52:35 INFO - 'TMOUT': '86400',
16:52:35 INFO - 'USER': 'cltbld',
16:52:35 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:35 INFO - '_': '/tools/buildbot/bin/python'}
16:52:36 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:36 INFO - Collecting blobuploader==1.2.4
16:52:37 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
16:52:38 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
16:52:39 INFO - Installing collected packages: requests, docopt, blobuploader
16:52:39 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
16:52:39 INFO - Return code: 0
16:52:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:52:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:52:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52:39 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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52:39 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
16:52:39 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
16:52:39 INFO - Using env: {'DISPLAY': ':0',
16:52:39 INFO - 'HOME': '/home/cltbld',
16:52:39 INFO - 'LANG': 'en_US.UTF-8',
16:52:39 INFO - 'LANGUAGE': 'en_US:en',
16:52:39 INFO - 'LOGNAME': 'cltbld',
16:52:39 INFO - 'MAIL': '/var/mail/cltbld',
16:52:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:39 INFO - 'MOZ_NO_REMOTE': '1',
16:52:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:39 INFO - 'NO_EM_RESTART': '1',
16:52:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:39 INFO - 'PWD': '/builds/slave/test',
16:52:39 INFO - 'SHELL': '/bin/bash',
16:52:39 INFO - 'SHLVL': '1',
16:52:39 INFO - 'TERM': 'linux',
16:52:39 INFO - 'TMOUT': '86400',
16:52:39 INFO - 'USER': 'cltbld',
16:52:39 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:39 INFO - '_': '/tools/buildbot/bin/python'}
16:52:40 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:40 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
16:52:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
16:52:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
16:52:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
16:52:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
16:52:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
16:52:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
16:52:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
16:52:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
16:52:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
16:52:42 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
16:52:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
16:52:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
16:52:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
16:52:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
16:52:42 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
16:52:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
16:52:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
16:52:43 INFO - Running setup.py install for manifestparser: started
16:52:43 INFO - Running setup.py install for manifestparser: finished with status 'done'
16:52:43 INFO - Running setup.py install for mozcrash: started
16:52:43 INFO - Running setup.py install for mozcrash: finished with status 'done'
16:52:43 INFO - Running setup.py install for mozdebug: started
16:52:43 INFO - Running setup.py install for mozdebug: finished with status 'done'
16:52:43 INFO - Running setup.py install for mozdevice: started
16:52:44 INFO - Running setup.py install for mozdevice: finished with status 'done'
16:52:44 INFO - Running setup.py install for mozfile: started
16:52:44 INFO - Running setup.py install for mozfile: finished with status 'done'
16:52:44 INFO - Running setup.py install for mozhttpd: started
16:52:44 INFO - Running setup.py install for mozhttpd: finished with status 'done'
16:52:44 INFO - Running setup.py install for mozinfo: started
16:52:44 INFO - Running setup.py install for mozinfo: finished with status 'done'
16:52:44 INFO - Running setup.py install for mozInstall: started
16:52:45 INFO - Running setup.py install for mozInstall: finished with status 'done'
16:52:45 INFO - Running setup.py install for mozleak: started
16:52:45 INFO - Running setup.py install for mozleak: finished with status 'done'
16:52:45 INFO - Running setup.py install for mozlog: started
16:52:45 INFO - Running setup.py install for mozlog: finished with status 'done'
16:52:45 INFO - Running setup.py install for moznetwork: started
16:52:45 INFO - Running setup.py install for moznetwork: finished with status 'done'
16:52:45 INFO - Running setup.py install for mozprocess: started
16:52:45 INFO - Running setup.py install for mozprocess: finished with status 'done'
16:52:45 INFO - Running setup.py install for mozprofile: started
16:52:46 INFO - Running setup.py install for mozprofile: finished with status 'done'
16:52:46 INFO - Running setup.py install for mozrunner: started
16:52:46 INFO - Running setup.py install for mozrunner: finished with status 'done'
16:52:46 INFO - Running setup.py install for mozscreenshot: started
16:52:46 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
16:52:46 INFO - Running setup.py install for moztest: started
16:52:46 INFO - Running setup.py install for moztest: finished with status 'done'
16:52:46 INFO - Running setup.py install for mozversion: started
16:52:47 INFO - Running setup.py install for mozversion: finished with status 'done'
16:52:47 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
16:52:47 INFO - Return code: 0
16:52:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52:47 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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52:47 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
16:52:47 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
16:52:47 INFO - Using env: {'DISPLAY': ':0',
16:52:47 INFO - 'HOME': '/home/cltbld',
16:52:47 INFO - 'LANG': 'en_US.UTF-8',
16:52:47 INFO - 'LANGUAGE': 'en_US:en',
16:52:47 INFO - 'LOGNAME': 'cltbld',
16:52:47 INFO - 'MAIL': '/var/mail/cltbld',
16:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:47 INFO - 'MOZ_NO_REMOTE': '1',
16:52:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:47 INFO - 'NO_EM_RESTART': '1',
16:52:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:47 INFO - 'PWD': '/builds/slave/test',
16:52:47 INFO - 'SHELL': '/bin/bash',
16:52:47 INFO - 'SHLVL': '1',
16:52:47 INFO - 'TERM': 'linux',
16:52:47 INFO - 'TMOUT': '86400',
16:52:47 INFO - 'USER': 'cltbld',
16:52:47 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:47 INFO - '_': '/tools/buildbot/bin/python'}
16:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:47 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
16:52:48 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))
16:52:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
16:52:48 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))
16:52:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
16:52:48 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))
16:52:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
16:52:48 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))
16:52:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
16:52:48 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))
16:52:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
16:52:49 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))
16:52:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
16:52:49 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))
16:52:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
16:52:49 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))
16:52:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
16:52:49 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))
16:52:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
16:52:49 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))
16:52:49 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
16:52:50 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))
16:52:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
16:52:50 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))
16:52:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
16:52:50 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))
16:52:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
16:52:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
16:52:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
16:52:50 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))
16:52:50 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
16:52:51 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))
16:52:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
16:52:51 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))
16:52:51 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
16:52:52 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
16:52:52 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))
16:52:52 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))
16:52:52 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))
16:52:52 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))
16:52:52 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))
16:52:52 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))
16:52:52 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
16:52:53 INFO - Installing collected packages: six, blessings
16:52:53 INFO - Successfully installed blessings-1.6 six-1.10.0
16:52:53 INFO - Return code: 0
16:52:53 INFO - Done creating virtualenv /builds/slave/test/build/venv.
16:52:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:52:54 INFO - Reading from file tmpfile_stdout
16:52:54 INFO - Reading from file tmpfile_stderr
16:52:54 INFO - Current package versions:
16:52:54 INFO - blessings == 1.6
16:52:54 INFO - blobuploader == 1.2.4
16:52:54 INFO - docopt == 0.6.1
16:52:54 INFO - functools32 == 3.2.3.post2
16:52:54 INFO - jsonschema == 2.5.1
16:52:54 INFO - manifestparser == 1.2
16:52:54 INFO - mozInstall == 1.14
16:52:54 INFO - mozcrash == 1.0
16:52:54 INFO - mozdebug == 0.1
16:52:54 INFO - mozdevice == 0.51
16:52:54 INFO - mozfile == 1.2
16:52:54 INFO - mozhttpd == 0.7
16:52:54 INFO - mozinfo == 0.10
16:52:54 INFO - mozleak == 0.1
16:52:54 INFO - mozlog == 3.5
16:52:54 INFO - moznetwork == 0.27
16:52:54 INFO - mozprocess == 0.25
16:52:54 INFO - mozprofile == 0.29
16:52:54 INFO - mozrunner == 6.13
16:52:54 INFO - mozscreenshot == 0.1
16:52:54 INFO - mozsystemmonitor == 0.3
16:52:54 INFO - moztest == 0.8
16:52:54 INFO - mozversion == 1.4
16:52:54 INFO - psutil == 3.1.1
16:52:54 INFO - requests == 1.2.3
16:52:54 INFO - six == 1.10.0
16:52:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv
16:52: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')]}
16:52:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:52: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')]}
16:52:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:52:54 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 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:52:54 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
16:52:54 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
16:52:54 INFO - Using env: {'DISPLAY': ':0',
16:52:54 INFO - 'HOME': '/home/cltbld',
16:52:54 INFO - 'LANG': 'en_US.UTF-8',
16:52:54 INFO - 'LANGUAGE': 'en_US:en',
16:52:54 INFO - 'LOGNAME': 'cltbld',
16:52:54 INFO - 'MAIL': '/var/mail/cltbld',
16:52:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:52:54 INFO - 'MOZ_NO_REMOTE': '1',
16:52:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:52:54 INFO - 'NO_EM_RESTART': '1',
16:52:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:52:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:52:54 INFO - 'PWD': '/builds/slave/test',
16:52:54 INFO - 'SHELL': '/bin/bash',
16:52:54 INFO - 'SHLVL': '1',
16:52:54 INFO - 'TERM': 'linux',
16:52:54 INFO - 'TMOUT': '86400',
16:52:54 INFO - 'USER': 'cltbld',
16:52:54 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:52:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:52:54 INFO - '_': '/tools/buildbot/bin/python'}
16:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple
16:52:54 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))
16:52:54 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))
16:52:54 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))
16:52:54 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))
16:52:54 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))
16:52:54 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))
16:52:54 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))
16:52:54 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))
16:52:54 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))
16:52:54 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
16:52:55 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:52:56 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
16:52:56 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))
16:52:56 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))
16:52:56 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))
16:52:56 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))
16:52:57 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
16:52:57 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:52:58 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
16:52:58 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:52:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
16:52:59 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
16:53:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
16:53:00 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
16:53:01 INFO - Found existing installation: requests 1.2.3
16:53:01 INFO - Uninstalling requests-1.2.3:
16:53:01 INFO - Successfully uninstalled requests-1.2.3
16:53:01 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
16:53:01 INFO - Return code: 0
16:53:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
16:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
16:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
16:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
16:53:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b8d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d67a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f06d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, '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': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727', '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
16:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
16:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
16:53:01 INFO - Using env: {'DISPLAY': ':0',
16:53:01 INFO - 'HOME': '/home/cltbld',
16:53:01 INFO - 'LANG': 'en_US.UTF-8',
16:53:01 INFO - 'LANGUAGE': 'en_US:en',
16:53:01 INFO - 'LOGNAME': 'cltbld',
16:53:01 INFO - 'MAIL': '/var/mail/cltbld',
16:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:53:01 INFO - 'MOZ_NO_REMOTE': '1',
16:53:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:53:01 INFO - 'NO_EM_RESTART': '1',
16:53:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:53:01 INFO - 'PWD': '/builds/slave/test',
16:53:01 INFO - 'SHELL': '/bin/bash',
16:53:01 INFO - 'SHLVL': '1',
16:53:01 INFO - 'TERM': 'linux',
16:53:01 INFO - 'TMOUT': '86400',
16:53:01 INFO - 'USER': 'cltbld',
16:53:01 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:53:01 INFO - '_': '/tools/buildbot/bin/python'}
16:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple
16:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
16:53:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
16:53:01 INFO - Return code: 0
16:53:01 INFO - Running post-action listener: _resource_record_post_action
16:53:01 INFO - Running post-action listener: _start_resource_monitoring
16:53:01 INFO - Starting resource monitoring.
16:53:01 INFO - [mozharness: 2017-11-07 00:53:01.922527Z] Finished create-virtualenv step (success)
16:53:01 INFO - [mozharness: 2017-11-07 00:53:01.923032Z] Running install step.
16:53:01 INFO - Running pre-action listener: _resource_record_pre_action
16:53:01 INFO - Running main action method: install
16:53:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
16:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
16:53:02 INFO - Reading from file tmpfile_stdout
16:53:02 INFO - Reading from file tmpfile_stderr
16:53:02 INFO - Detecting whether we're running mozinstall >=1.0...
16:53:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
16:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
16:53:02 INFO - Reading from file tmpfile_stdout
16:53:02 INFO - Output received:
16:53:02 INFO - Usage: mozinstall [options] installer
16:53:02 INFO - Options:
16:53:02 INFO - -h, --help show this help message and exit
16:53:02 INFO - -d DEST, --destination=DEST
16:53:02 INFO - Directory to install application into. [default:
16:53:02 INFO - "/builds/slave/test"]
16:53:02 INFO - --app=APP Application being installed. [default: firefox]
16:53:02 INFO - mkdir: /builds/slave/test/build/application
16:53:02 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']
16:53:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
16:53:20 INFO - Reading from file tmpfile_stdout
16:53:20 INFO - Output received:
16:53:20 INFO - /builds/slave/test/build/application/firefox/firefox
16:53:20 INFO - Running post-action listener: _resource_record_post_action
16:53:20 INFO - [mozharness: 2017-11-07 00:53:20.685851Z] Finished install step (success)
16:53:20 INFO - [mozharness: 2017-11-07 00:53:20.686098Z] Running setup-mitmproxy step.
16:53:20 INFO - Running pre-action listener: _resource_record_pre_action
16:53:20 INFO - Running main action method: setup_mitmproxy
16:53:20 INFO - Skipping: mitmproxy is not required
16:53:20 INFO - Running post-action listener: _resource_record_post_action
16:53:20 INFO - [mozharness: 2017-11-07 00:53:20.687209Z] Finished setup-mitmproxy step (success)
16:53:20 INFO - [mozharness: 2017-11-07 00:53:20.687428Z] Running run-tests step.
16:53:20 INFO - Running pre-action listener: _resource_record_pre_action
16:53:20 INFO - Running pre-action listener: _set_gcov_prefix
16:53:20 INFO - Running main action method: run_tests
16:53:20 WARNING - Try message not found.
16:53:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
16:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
16:53:20 INFO - Python 2.7.3
16:53:20 INFO - Return code: 0
16:53:20 INFO - grabbing minidump binary from tooltool
16:53:20 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')]}
16:53:20 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 0x1f0a070>, '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 0x1f0b080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f052a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
16:53:20 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
16:53:20 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
16:53:20 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
16:53:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
16:53:20 INFO - Return code: 0
16:53:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
16:53:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
16:53:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
16:53:20 INFO - ENV: RUST_BACKTRACE is now full
16:53:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
16:53:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
16:53:20 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/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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
16:53:20 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/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-003 --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
16:53:20 INFO - Using env: {'DISPLAY': ':0',
16:53:20 INFO - 'HOME': '/home/cltbld',
16:53:20 INFO - 'LANG': 'en_US.UTF-8',
16:53:20 INFO - 'LANGUAGE': 'en_US:en',
16:53:20 INFO - 'LOGNAME': 'cltbld',
16:53:20 INFO - 'MAIL': '/var/mail/cltbld',
16:53:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:53:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:53:20 INFO - 'MOZ_NO_REMOTE': '1',
16:53:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:53:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:53:20 INFO - 'NO_EM_RESTART': '1',
16:53:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:53:20 INFO - 'PWD': '/builds/slave/test',
16:53:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:53:20 INFO - 'RUST_BACKTRACE': 'full',
16:53:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:53:20 INFO - 'SHELL': '/bin/bash',
16:53:20 INFO - 'SHLVL': '1',
16:53:20 INFO - 'TERM': 'linux',
16:53:20 INFO - 'TMOUT': '86400',
16:53:20 INFO - 'USER': 'cltbld',
16:53:20 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:53:20 INFO - '_': '/tools/buildbot/bin/python'}
16:53:20 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/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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
16:53:21 INFO - mozversion application_buildid: 20171106230605
16:53:21 INFO - mozversion application_changeset: c2fe4b3b1b930b3e7fdb84eae44cec165394f322
16:53:21 INFO - mozversion application_display_name: Nightly
16:53:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
16:53:21 INFO - mozversion application_name: Firefox
16:53:21 INFO - mozversion application_remotingname: firefox
16:53:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
16:53:21 INFO - mozversion application_vendor: Mozilla
16:53:21 INFO - mozversion application_version: 58.0a1
16:53:21 INFO - mozversion platform_buildid: 20171106230605
16:53:21 INFO - mozversion platform_changeset: c2fe4b3b1b930b3e7fdb84eae44cec165394f322
16:53:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
16:53:21 INFO - mozversion platform_version: 58.0a1
16:53:21 INFO - using testdate: 1510016001
16:53:21 INFO - actual date: 1510016001
16:53:21 INFO - starting webserver on 'localhost:46358'
16:53:21 INFO - SUITE-START | Running 3 tests
16:53:21 INFO - TEST-START | basic_compositor_video
16:53:21 INFO - Initialising browser for basic_compositor_video test...
16:53:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46358/getInfo.html -profile /tmp/tmpC8e42_/profile
16:53:21 INFO - TEST-INFO | started process 25280 (/builds/slave/test/build/application/firefox/firefox http://localhost:46358/getInfo.html)
16:53:26 INFO - TEST-INFO | 25280: exit 0
16:53:26 INFO - Browser initialized.
16:53:26 INFO - Running cycle 1/1 for basic_compositor_video test...
16:53:26 INFO - Using env: {'DISPLAY': ':0',
16:53:26 INFO - 'HOME': '/home/cltbld',
16:53:26 INFO - 'JSGC_DISABLE_POISONING': '1',
16:53:26 INFO - 'LANG': 'en_US.UTF-8',
16:53:26 INFO - 'LANGUAGE': 'en_US:en',
16:53:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
16:53:26 INFO - 'LOGNAME': 'cltbld',
16:53:26 INFO - 'MAIL': '/var/mail/cltbld',
16:53:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
16:53:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
16:53:26 INFO - 'MOZ_CRASHREPORTER': '1',
16:53:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
16:53:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
16:53:26 INFO - 'MOZ_NO_REMOTE': '1',
16:53:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
16:53:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
16:53:26 INFO - 'NO_EM_RESTART': '1',
16:53:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
16:53:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
16:53:26 INFO - 'PWD': '/builds/slave/test',
16:53:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
16:53:26 INFO - 'RUST_BACKTRACE': 'full',
16:53:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
16:53:26 INFO - 'SHELL': '/bin/bash',
16:53:26 INFO - 'SHLVL': '1',
16:53:26 INFO - 'TERM': 'linux',
16:53:26 INFO - 'TMOUT': '86400',
16:53:26 INFO - 'USER': 'cltbld',
16:53:26 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
16:53:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
16:53:26 INFO - '_': '/tools/buildbot/bin/python'}
16:53:26 INFO - TEST-INFO | started process 25552 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC8e42_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
16:53:27 INFO - PID 25552 |
16:53:27 INFO - PID 25552 | (/builds/slave/test/build/application/firefox/firefox:25603): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:53:27 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6839421997755333 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6700542570951582 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7047642045454547 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6992990654205606 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 3.911773142112125 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.5778354203935603 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.4732581018518545 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.378986486486481 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.725734732824427 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.481904109589042 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.239180887372008 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 9.664879227053145 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.952335766423353 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.472748691099506 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.661286919831218 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.195579268292665 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586833976833969 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.239887640449421 ms/frame
16:54:13 INFO - PID 25552 |
16:54:13 INFO - PID 25552 | Cycle 1(1): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
16:54:59 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6922645234066556 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669458333333333 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.762867215041128 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7624713656387672 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116069958847737 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.016686746987951 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.4893023255813964 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.379349662162157 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769990384615386 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.511460055096419 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.312027491408932 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.156979695431469 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.950693430656926 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.929617486338811 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.608130252100842 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97745508982032 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.280751879699249 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.814324324324332 ms/frame
16:54:59 INFO - PID 25552 |
16:54:59 INFO - PID 25552 | Cycle 1(2): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
16:55:45 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6934650112866818 ms/frame
16:55:45 INFO - PID 25552 |
16:55:45 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6725627090301005 ms/frame
16:55:45 INFO - PID 25552 |
16:55:45 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.752535046728972 ms/frame
16:55:45 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7320562770562775 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.161338418862689 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.132293388429753 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.5390507075471715 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4669497400346625 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.651337099811677 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5739554317548725 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.101734006734008 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.206122448979581 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.321981132075475 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.756236559139804 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607310924369752 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83857988165681 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.585482625482623 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.301666666666689 ms/frame
16:55:46 INFO - PID 25552 |
16:55:46 INFO - PID 25552 | Cycle 1(3): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
16:56:32 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.67981522956327 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6685029190992495 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.706968145620023 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7040800681431005 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9689021164021185 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.14979253112033 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.472876157407408 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.396612903225806 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.661867924528304 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634014084507034 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.102760942760945 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.105707070707073 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.23853932584269 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.874864130434775 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348641975308647 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.9802994011976 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.496532567049814 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.873885869565209 ms/frame
16:56:32 INFO - PID 25552 |
16:56:32 INFO - PID 25552 | Cycle 1(4): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
16:57:18 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789759373251258 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6671541666666667 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.716330091533181 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6937002540220154 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.162323162274621 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.050546558704452 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.4888023255813962 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.448784482758618 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.673128544423439 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.541066481994462 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.38217993079585 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.206607142857138 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114962962962979 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.58656084656085 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.299467213114752 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.631308139534879 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.36757575757576 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.697085561497305 ms/frame
16:57:18 INFO - PID 25552 |
16:57:18 INFO - PID 25552 | Cycle 1(5): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
16:58:04 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.685502808988764 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6662864279766858 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.772055522740697 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7158404802744427 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9477894736842103 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9844721115537847 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.4564112903225817 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.351767169179225 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640469924812029 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510702479338849 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.069177852348998 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.312963917525774 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.410798479087441 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81316216216219 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.448506224066406 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.770764705882346 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.767549019607838 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.175837988826807 ms/frame
16:58:04 INFO - PID 25552 |
16:58:04 INFO - PID 25552 | Cycle 1(6): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
16:58:27 INFO - PID 25552 |
16:58:27 INFO - PID 25552 | (/builds/slave/test/build/application/firefox/firefox:25688): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
16:58:27 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6808991596638656 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6702420701168612 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.738415411355736 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7483304195804197 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.065474254742547 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.149948132780082 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.472395833333333 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.350293132328308 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693538899430745 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.420542005420055 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.345293103448283 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 9.712572815533985 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.157732342007423 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.756801075268815 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607773109243704 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.048162650602425 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.282048872180457 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.816405405405385 ms/frame
16:58:50 INFO - PID 25552 |
16:58:50 INFO - PID 25552 | Cycle 1(7): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
16:59:36 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6837934904601575 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684778982485406 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7283986175115207 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6996474086661 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.213848314606742 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.7956356736242904 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.713038366336634 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5666488413547226 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6622169811320795 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.391805929919139 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.384377162629756 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.314355670103096 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.990750915750924 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.473612565445022 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660147679324902 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052168674698772 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.236292134831475 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113027777777743 ms/frame
16:59:36 INFO - PID 25552 |
16:59:36 INFO - PID 25552 | Cycle 1(8): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
17:00:23 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686787521079258 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668527939949958 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.748706293706294 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7454188481675392 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.126980742778542 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.13262396694215 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.5258754406580497 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4316638078902226 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769625000000003 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.541675900277004 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.526578947368424 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.205255102040814 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.031452205882355 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.476335078534047 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.245591836734686 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835591715976355 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.239588014981278 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.641489361702158 ms/frame
17:00:23 INFO - PID 25552 |
17:00:23 INFO - PID 25552 | Cycle 1(9): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
17:01:09 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6780145413870247 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6692368640533777 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7186569301260022 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6951059322033895 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093287858117325 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.099026639344264 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.5464184397163128 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4491465517241395 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.555712962962962 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.363069705093832 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.489825174825175 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.368290155440423 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.197462686567158 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.757688172043013 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.345781893004121 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.76708823529411 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451164122137383 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.174078212290523 ms/frame
17:01:09 INFO - PID 25552 |
17:01:09 INFO - PID 25552 | Cycle 1(10): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
17:01:55 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6793033016228316 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687531276063385 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7126769406392697 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.69639100932994 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.011163101604276 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.168218750000002 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.5217664319248847 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.40253401360544 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.662339622641511 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634507042253521 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 10.41751736111112 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 9.857093596059103 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.238614232209748 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.816108108108086 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.44910788381743 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.195762195121926 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.409524714828876 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991236263736257 ms/frame
17:01:55 INFO - PID 25552 |
17:01:55 INFO - PID 25552 | Cycle 1(11): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
17:02:41 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6794292109681028 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669482470784641 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7212162937464142 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7320216450216455 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_startup = 4.105499316005471 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.135051652892566 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_startup = 3.4652829099307154 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3956536502546704 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6729489603024605 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.58864525139665 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_startup = 9.773697068403902 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.480p.60fps.webm_scale_2_inclip = 10.104343434343434 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.280902255639102 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.582380952380952 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.297684426229516 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766029411764697 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49691570881226 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.870380434782616 ms/frame
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | Cycle 1(12): loaded http://localhost:46358/tests/video/video_playback.html (next: http://localhost:46358/tests/video/video_playback.html)
17:02:41 INFO - PID 25552 | __start_tp_report
17:02:41 INFO - PID 25552 | _x_x_mozilla_page_load
17:02:41 INFO - PID 25552 | _x_x_mozilla_page_load_details
17:02:41 INFO - PID 25552 | |i|pagename|runs|
17:02:41 INFO - PID 25552 | |0;240p.120fps.mp4_scale_1_startup;1.6839421997755333;1.6922645234066556;1.6934650112866818;1.67981522956327;1.6789759373251258;1.685502808988764;1.6808991596638656;1.6837934904601575;1.686787521079258;1.6780145413870247;1.6793033016228316;1.6794292109681028
17:02:41 INFO - PID 25552 | |1;240p.120fps.mp4_scale_1_inclip;1.6700542570951582;1.6669458333333333;1.6725627090301005;1.6685029190992495;1.6671541666666667;1.6662864279766858;1.6702420701168612;1.6684778982485406;1.668527939949958;1.6692368640533777;1.6687531276063385;1.669482470784641
17:02:41 INFO - PID 25552 | |2;240p.120fps.mp4_scale_1.1_startup;1.7047642045454547;1.762867215041128;1.752535046728972;1.706968145620023;1.716330091533181;1.772055522740697;1.738415411355736;1.7283986175115207;1.748706293706294;1.7186569301260022;1.7126769406392697;1.7212162937464142
17:02:41 INFO - PID 25552 | |3;240p.120fps.mp4_scale_1.1_inclip;1.6992990654205606;1.7624713656387672;1.7320562770562775;1.7040800681431005;1.6937002540220154;1.7158404802744427;1.7483304195804197;1.6996474086661;1.7454188481675392;1.6951059322033895;1.69639100932994;1.7320216450216455
17:02:41 INFO - PID 25552 | |4;240p.120fps.mp4_scale_2_startup;3.911773142112125;4.116069958847737;4.161338418862689;3.9689021164021185;4.162323162274621;3.9477894736842103;4.065474254742547;4.213848314606742;4.126980742778542;4.093287858117325;4.011163101604276;4.105499316005471
17:02:41 INFO - PID 25552 | |5;240p.120fps.mp4_scale_2_inclip;3.5778354203935603;4.016686746987951;4.132293388429753;4.14979253112033;4.050546558704452;3.9844721115537847;4.149948132780082;3.7956356736242904;4.13262396694215;4.099026639344264;4.168218750000002;4.135051652892566
17:02:41 INFO - PID 25552 | |6;480p.60fps.webm_scale_1_startup;3.4732581018518545;3.4893023255813964;3.5390507075471715;3.472876157407408;3.4888023255813962;3.4564112903225817;3.472395833333333;3.713038366336634;3.5258754406580497;3.5464184397163128;3.5217664319248847;3.4652829099307154
17:02:41 INFO - PID 25552 | |7;480p.60fps.webm_scale_1_inclip;3.378986486486481;3.379349662162157;3.4669497400346625;3.396612903225806;3.448784482758618;3.351767169179225;3.350293132328308;3.5666488413547226;3.4316638078902226;3.4491465517241395;3.40253401360544;3.3956536502546704
17:02:41 INFO - PID 25552 | |8;480p.60fps.webm_scale_1.1_startup;5.725734732824427;5.769990384615386;5.651337099811677;5.661867924528304;5.673128544423439;5.640469924812029;5.693538899430745;5.6622169811320795;5.769625000000003;5.555712962962962;5.662339622641511;5.6729489603024605
17:02:41 INFO - PID 25552 | |9;480p.60fps.webm_scale_1.1_inclip;5.481904109589042;5.511460055096419;5.5739554317548725;5.634014084507034;5.541066481994462;5.510702479338849;5.420542005420055;5.391805929919139;5.541675900277004;5.363069705093832;5.634507042253521;5.58864525139665
17:02:41 INFO - PID 25552 | |10;480p.60fps.webm_scale_2_startup;10.239180887372008;10.312027491408932;10.101734006734008;10.102760942760945;10.38217993079585;10.069177852348998;10.345293103448283;10.384377162629756;10.526578947368424;10.489825174825175;10.41751736111112;9.773697068403902
17:02:41 INFO - PID 25552 | |11;480p.60fps.webm_scale_2_inclip;9.664879227053145;10.156979695431469;10.206122448979581;10.105707070707073;10.206607142857138;10.312963917525774;9.712572815533985;10.314355670103096;10.205255102040814;10.368290155440423;9.857093596059103;10.104343434343434
17:02:41 INFO - PID 25552 | |12;1080p.60fps.mp4_scale_1_startup;10.952335766423353;10.950693430656926;11.321981132075475;11.23853932584269;11.114962962962979;11.410798479087441;11.157732342007423;10.990750915750924;11.031452205882355;11.197462686567158;11.238614232209748;11.280902255639102
17:02:41 INFO - PID 25552 | |13;1080p.60fps.mp4_scale_1_inclip;10.472748691099506;10.929617486338811;10.756236559139804;10.874864130434775;10.58656084656085;10.81316216216219;10.756801075268815;10.473612565445022;10.476335078534047;10.757688172043013;10.816108108108086;10.582380952380952
17:02:41 INFO - PID 25552 | |14;1080p.60fps.mp4_scale_1.1_startup;12.661286919831218;12.608130252100842;12.607310924369752;12.348641975308647;12.299467213114752;12.448506224066406;12.607773109243704;12.660147679324902;12.245591836734686;12.345781893004121;12.44910788381743;12.297684426229516
17:02:41 INFO - PID 25552 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.195579268292665;11.97745508982032;11.83857988165681;11.9802994011976;11.631308139534879;11.770764705882346;12.048162650602425;12.052168674698772;11.835591715976355;11.76708823529411;12.195762195121926;11.766029411764697
17:02:41 INFO - PID 25552 | |16;1080p.60fps.mp4_scale_2_startup;11.586833976833969;11.280751879699249;11.585482625482623;11.496532567049814;11.36757575757576;11.767549019607838;11.282048872180457;11.236292134831475;11.239588014981278;11.451164122137383;11.409524714828876;11.49691570881226
17:02:41 INFO - PID 25552 | |17;1080p.60fps.mp4_scale_2_inclip;11.239887640449421;10.814324324324332;11.301666666666689;10.873885869565209;10.697085561497305;11.175837988826807;10.816405405405385;11.113027777777743;10.641489361702158;11.174078212290523;10.991236263736257;10.870380434782616
17:02:41 INFO - PID 25552 | __end_tp_report
17:02:41 INFO - PID 25552 | __start_cc_report
17:02:41 INFO - PID 25552 | _x_x_mozilla_cycle_collect,577
17:02:41 INFO - PID 25552 | __end_cc_report
17:02:41 INFO - PID 25552 | __startTimestamp1510016561848__endTimestamp
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | ------- Summary: start -------
17:02:41 INFO - PID 25552 | Number of tests: 18
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.01 (0.3%) stddev-sans-first:0.01
17:02:41 INFO - PID 25552 | 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
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#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
17:02:41 INFO - PID 25552 | 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
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.73 Median:1.73 stddev:0.02 (1.3%) stddev-sans-first:0.02
17:02:41 INFO - PID 25552 | Values: 1.7 1.8 1.8 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.7
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.72 Median:1.72 stddev:0.02 (1.4%) stddev-sans-first:0.02
17:02:41 INFO - PID 25552 | Values: 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.07 Median:4.11 stddev:0.09 (2.3%) stddev-sans-first:0.08
17:02:41 INFO - PID 25552 | Values: 3.9 4.1 4.2 4.0 4.2 3.9 4.1 4.2 4.1 4.1 4.0 4.1
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.03 Median:4.13 stddev:0.18 (4.3%) stddev-sans-first:0.11
17:02:41 INFO - PID 25552 | Values: 3.6 4.0 4.1 4.1 4.1 4.0 4.1 3.8 4.1 4.1 4.2 4.1
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.51 Median:3.51 stddev:0.07 (2.0%) stddev-sans-first:0.07
17:02:41 INFO - PID 25552 | Values: 3.5 3.5 3.5 3.5 3.5 3.5 3.5 3.7 3.5 3.5 3.5 3.5
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.42 stddev:0.06 (1.8%) stddev-sans-first:0.06
17:02:41 INFO - PID 25552 | Values: 3.4 3.4 3.5 3.4 3.4 3.4 3.4 3.6 3.4 3.4 3.4 3.4
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.68 Median:5.67 stddev:0.06 (1.0%) stddev-sans-first:0.06
17:02:41 INFO - PID 25552 | Values: 5.7 5.8 5.7 5.7 5.7 5.6 5.7 5.7 5.8 5.6 5.7 5.7
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.52 Median:5.54 stddev:0.09 (1.6%) stddev-sans-first:0.09
17:02:41 INFO - PID 25552 | Values: 5.5 5.5 5.6 5.6 5.5 5.5 5.4 5.4 5.5 5.4 5.6 5.6
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.26 Median:10.38 stddev:0.22 (2.1%) stddev-sans-first:0.23
17:02:41 INFO - PID 25552 | Values: 10.2 10.3 10.1 10.1 10.4 10.1 10.3 10.4 10.5 10.5 10.4 9.8
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.10 Median:10.31 stddev:0.23 (2.3%) stddev-sans-first:0.20
17:02:41 INFO - PID 25552 | Values: 9.7 10.2 10.2 10.1 10.2 10.3 9.7 10.3 10.2 10.4 9.9 10.1
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.16 Median:11.22 stddev:0.15 (1.3%) stddev-sans-first:0.14
17:02:41 INFO - PID 25552 | Values: 11.0 11.0 11.3 11.2 11.1 11.4 11.2 11.0 11.0 11.2 11.2 11.3
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.69 Median:10.76 stddev:0.16 (1.5%) stddev-sans-first:0.16
17:02:41 INFO - PID 25552 | Values: 10.5 10.9 10.8 10.9 10.6 10.8 10.8 10.5 10.5 10.8 10.8 10.6
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.46 Median:12.53 stddev:0.16 (1.2%) stddev-sans-first:0.15
17:02:41 INFO - PID 25552 | Values: 12.7 12.6 12.6 12.3 12.3 12.4 12.6 12.7 12.2 12.3 12.4 12.3
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.92 Median:11.98 stddev:0.18 (1.5%) stddev-sans-first:0.17
17:02:41 INFO - PID 25552 | Values: 12.2 12.0 11.8 12.0 11.6 11.8 12.0 12.1 11.8 11.8 12.2 11.8
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.43 Median:11.47 stddev:0.16 (1.4%) stddev-sans-first:0.16
17:02:41 INFO - PID 25552 | Values: 11.6 11.3 11.6 11.5 11.4 11.8 11.3 11.2 11.2 11.5 11.4 11.5
17:02:41 INFO - PID 25552 |
17:02:41 INFO - PID 25552 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.98 Median:11.05 stddev:0.22 (2.0%) stddev-sans-first:0.21
17:02:41 INFO - PID 25552 | Values: 11.2 10.8 11.3 10.9 10.7 11.2 10.8 11.1 10.6 11.2 11.0 10.9
17:02:41 INFO - PID 25552 | -------- Summary: end --------
17:02:41 INFO - PID 25552 |
17:02:42 INFO - TEST-INFO | 25552: exit 0
17:02:42 INFO - TEST-OK | basic_compositor_video | took 561286ms
17:02:42 INFO - TEST-START | glvideo
17:02:42 INFO - Initialising browser for glvideo test...
17:02:42 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46358/getInfo.html -profile /tmp/tmpWAoy45/profile
17:02:42 INFO - TEST-INFO | started process 26849 (/builds/slave/test/build/application/firefox/firefox http://localhost:46358/getInfo.html)
17:02:48 INFO - TEST-INFO | 26849: exit 0
17:02:48 INFO - Browser initialized.
17:02:48 INFO - Running cycle 1/1 for glvideo test...
17:02:48 INFO - Using env: {'DISPLAY': ':0',
17:02:48 INFO - 'HOME': '/home/cltbld',
17:02:48 INFO - 'JSGC_DISABLE_POISONING': '1',
17:02:48 INFO - 'LANG': 'en_US.UTF-8',
17:02:48 INFO - 'LANGUAGE': 'en_US:en',
17:02:48 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
17:02:48 INFO - 'LOGNAME': 'cltbld',
17:02:48 INFO - 'MAIL': '/var/mail/cltbld',
17:02:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
17:02:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
17:02:48 INFO - 'MOZ_CRASHREPORTER': '1',
17:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:02:48 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
17:02:48 INFO - 'MOZ_NO_REMOTE': '1',
17:02:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
17:02:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:02:48 INFO - 'NO_EM_RESTART': '1',
17:02:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:02:48 INFO - 'PWD': '/builds/slave/test',
17:02:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
17:02:48 INFO - 'RUST_BACKTRACE': 'full',
17:02:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
17:02:48 INFO - 'SHELL': '/bin/bash',
17:02:48 INFO - 'SHLVL': '1',
17:02:48 INFO - 'TERM': 'linux',
17:02:48 INFO - 'TMOUT': '86400',
17:02:48 INFO - 'USER': 'cltbld',
17:02:48 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
17:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:02:48 INFO - '_': '/tools/buildbot/bin/python'}
17:02:48 INFO - TEST-INFO | started process 27120 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWAoy45/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
17:02:48 INFO - PID 27120 |
17:02:48 INFO - PID 27120 | (/builds/slave/test/build/application/firefox/firefox:27172): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
17:02:48 INFO - PID 27120 |
17:02:50 INFO - PID 27120 | [talos glvideo result] Mean tick time across 100 ticks: 6.8248500000000005 ms
17:02:50 INFO - PID 27120 | Cycle 1(1): loaded http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html)
17:02:51 INFO - PID 27120 | [talos glvideo result] Mean tick time across 100 ticks: 6.5114 ms
17:02:51 INFO - PID 27120 | Cycle 1(2): loaded http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html)
17:02:53 INFO - PID 27120 | [talos glvideo result] Mean tick time across 100 ticks: 6.5305 ms
17:02:53 INFO - PID 27120 | Cycle 1(3): loaded http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html)
17:02:54 INFO - PID 27120 | [talos glvideo result] Mean tick time across 100 ticks: 6.57165 ms
17:02:54 INFO - PID 27120 | Cycle 1(4): loaded http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html)
17:02:55 INFO - PID 27120 | [talos glvideo result] Mean tick time across 100 ticks: 6.6697500000000005 ms
17:02:55 INFO - PID 27120 | Cycle 1(5): loaded http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46358/tests/webgl/benchmarks/video/video_upload.html)
17:02:55 INFO - PID 27120 | __start_tp_report
17:02:55 INFO - PID 27120 | _x_x_mozilla_page_load
17:02:55 INFO - PID 27120 | _x_x_mozilla_page_load_details
17:02:55 INFO - PID 27120 | |i|pagename|runs|
17:02:55 INFO - PID 27120 | |0;Mean tick time across 100 ticks: ;6.8248500000000005;6.5114;6.5305;6.57165;6.6697500000000005
17:02:55 INFO - PID 27120 | __end_tp_report
17:02:55 INFO - PID 27120 | __start_cc_report
17:02:55 INFO - PID 27120 | _x_x_mozilla_cycle_collect,282
17:02:55 INFO - PID 27120 | __end_cc_report
17:02:55 INFO - PID 27120 | __startTimestamp1510016575943__endTimestamp
17:02:55 INFO - PID 27120 |
17:02:55 INFO - PID 27120 | ------- Summary: start -------
17:02:55 INFO - PID 27120 | Number of tests: 1
17:02:55 INFO - PID 27120 |
17:02:55 INFO - PID 27120 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.62 Median:6.57 stddev:0.13 (2.0%) stddev-sans-first:0.07
17:02:55 INFO - PID 27120 | Values: 6.8 6.5 6.5 6.6 6.7
17:02:55 INFO - PID 27120 | -------- Summary: end --------
17:02:55 INFO - PID 27120 |
17:02:56 INFO - PID 27120 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
17:02:56 INFO - TEST-INFO | 27120: exit 0
17:02:56 INFO - TEST-OK | glvideo | took 14051ms
17:02:56 INFO - TEST-START | displaylist_mutate
17:02:56 INFO - Initialising browser for displaylist_mutate test...
17:02:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46358/getInfo.html -profile /tmp/tmpfaMNFs/profile
17:02:56 INFO - TEST-INFO | started process 27344 (/builds/slave/test/build/application/firefox/firefox http://localhost:46358/getInfo.html)
17:03:02 INFO - TEST-INFO | 27344: exit 0
17:03:02 INFO - Browser initialized.
17:03:02 INFO - Running cycle 1/1 for displaylist_mutate test...
17:03:02 INFO - Using env: {'DISPLAY': ':0',
17:03:02 INFO - 'HOME': '/home/cltbld',
17:03:02 INFO - 'JSGC_DISABLE_POISONING': '1',
17:03:02 INFO - 'LANG': 'en_US.UTF-8',
17:03:02 INFO - 'LANGUAGE': 'en_US:en',
17:03:02 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
17:03:02 INFO - 'LOGNAME': 'cltbld',
17:03:02 INFO - 'MAIL': '/var/mail/cltbld',
17:03:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
17:03:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
17:03:02 INFO - 'MOZ_CRASHREPORTER': '1',
17:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
17:03:02 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
17:03:02 INFO - 'MOZ_NO_REMOTE': '1',
17:03:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
17:03:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
17:03:02 INFO - 'NO_EM_RESTART': '1',
17:03:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
17:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
17:03:02 INFO - 'PWD': '/builds/slave/test',
17:03:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
17:03:02 INFO - 'RUST_BACKTRACE': 'full',
17:03:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
17:03:02 INFO - 'SHELL': '/bin/bash',
17:03:02 INFO - 'SHLVL': '1',
17:03:02 INFO - 'TERM': 'linux',
17:03:02 INFO - 'TMOUT': '86400',
17:03:02 INFO - 'USER': 'cltbld',
17:03:02 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1510015784.830348-724474727',
17:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
17:03:02 INFO - '_': '/tools/buildbot/bin/python'}
17:03:02 INFO - TEST-INFO | started process 27615 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfaMNFs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
17:03:02 INFO - PID 27615 |
17:03:02 INFO - PID 27615 | (/builds/slave/test/build/application/firefox/firefox:27666): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
17:03:02 INFO - PID 27615 |
17:03:21 INFO - PID 27615 | Cycle 1(1): loaded http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html)
17:03:41 INFO - PID 27615 | Cycle 1(2): loaded http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html)
17:03:41 INFO - PID 27615 | Waiting for idle-callback
17:04:00 INFO - PID 27615 | Cycle 1(3): loaded http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html)
17:04:20 INFO - PID 27615 | Cycle 1(4): loaded http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html)
17:04:39 INFO - PID 27615 | Cycle 1(5): loaded http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:46358/tests/layout/benchmarks/displaylist_mutate.html)
17:04:39 INFO - PID 27615 | __start_tp_report
17:04:39 INFO - PID 27615 | _x_x_mozilla_page_load
17:04:39 INFO - PID 27615 | _x_x_mozilla_page_load_details
17:04:39 INFO - PID 27615 | |i|pagename|runs|
17:04:39 INFO - PID 27615 | |0;/displaylist_mutate.html;18253.945000000003;18809.390000000003;18541.105;18437.955;18449.52
17:04:39 INFO - PID 27615 | __end_tp_report
17:04:39 INFO - PID 27615 | __start_cc_report
17:04:39 INFO - PID 27615 | _x_x_mozilla_cycle_collect,202
17:04:39 INFO - PID 27615 | __end_cc_report
17:04:39 INFO - PID 27615 | __startTimestamp1510016679449__endTimestamp
17:04:39 INFO - PID 27615 |
17:04:39 INFO - PID 27615 | ------- Summary: start -------
17:04:39 INFO - PID 27615 | Number of tests: 1
17:04:39 INFO - PID 27615 |
17:04:39 INFO - PID 27615 | [#0] /displaylist_mutate.html Cycles:5 Average:18498.38 Median:18449.52 stddev:202.69 (1.1%) stddev-sans-first:172.87
17:04:39 INFO - PID 27615 | Values: 18253.9 18809.4 18541.1 18438.0 18449.5
17:04:39 INFO - PID 27615 | -------- Summary: end --------
17:04:39 INFO - PID 27615 |
17:04:39 INFO - TEST-INFO | 27615: exit 0
17:04:40 INFO - TEST-OK | displaylist_mutate | took 103522ms
17:04:40 INFO - SUITE-END | took 678s
17:04:40 INFO - Completed test suite (00:11:18)
17:04:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.59359945554588, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6839421997755333, 1.6922645234066556, 1.6934650112866818, 1.67981522956327, 1.6789759373251258, 1.685502808988764, 1.6808991596638656, 1.6837934904601575, 1.686787521079258, 1.6780145413870247, 1.6793033016228316, 1.6794292109681028], "value": 1.6808991596638656, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6700542570951582, 1.6669458333333333, 1.6725627090301005, 1.6685029190992495, 1.6671541666666667, 1.6662864279766858, 1.6702420701168612, 1.6684778982485406, 1.668527939949958, 1.6692368640533777, 1.6687531276063385, 1.669482470784641], "value": 1.668527939949958, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7047642045454547, 1.762867215041128, 1.752535046728972, 1.706968145620023, 1.716330091533181, 1.772055522740697, 1.738415411355736, 1.7283986175115207, 1.748706293706294, 1.7186569301260022, 1.7126769406392697, 1.7212162937464142], "value": 1.7283986175115207, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6992990654205606, 1.7624713656387672, 1.7320562770562775, 1.7040800681431005, 1.6937002540220154, 1.7158404802744427, 1.7483304195804197, 1.6996474086661, 1.7454188481675392, 1.6951059322033895, 1.69639100932994, 1.7320216450216455], "value": 1.7158404802744427, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.911773142112125, 4.116069958847737, 4.161338418862689, 3.9689021164021185, 4.162323162274621, 3.9477894736842103, 4.065474254742547, 4.213848314606742, 4.126980742778542, 4.093287858117325, 4.011163101604276, 4.105499316005471], "value": 4.105499316005471, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5778354203935603, 4.016686746987951, 4.132293388429753, 4.14979253112033, 4.050546558704452, 3.9844721115537847, 4.149948132780082, 3.7956356736242904, 4.13262396694215, 4.099026639344264, 4.168218750000002, 4.135051652892566], "value": 4.132293388429753, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4732581018518545, 3.4893023255813964, 3.5390507075471715, 3.472876157407408, 3.4888023255813962, 3.4564112903225817, 3.472395833333333, 3.713038366336634, 3.5258754406580497, 3.5464184397163128, 3.5217664319248847, 3.4652829099307154], "value": 3.4893023255813964, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.378986486486481, 3.379349662162157, 3.4669497400346625, 3.396612903225806, 3.448784482758618, 3.351767169179225, 3.350293132328308, 3.5666488413547226, 3.4316638078902226, 3.4491465517241395, 3.40253401360544, 3.3956536502546704], "value": 3.40253401360544, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.725734732824427, 5.769990384615386, 5.651337099811677, 5.661867924528304, 5.673128544423439, 5.640469924812029, 5.693538899430745, 5.6622169811320795, 5.769625000000003, 5.555712962962962, 5.662339622641511, 5.6729489603024605], "value": 5.662339622641511, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.481904109589042, 5.511460055096419, 5.5739554317548725, 5.634014084507034, 5.541066481994462, 5.510702479338849, 5.420542005420055, 5.391805929919139, 5.541675900277004, 5.363069705093832, 5.634507042253521, 5.58864525139665], "value": 5.541066481994462, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.239180887372008, 10.312027491408932, 10.101734006734008, 10.102760942760945, 10.38217993079585, 10.069177852348998, 10.345293103448283, 10.384377162629756, 10.526578947368424, 10.489825174825175, 10.41751736111112, 9.773697068403902], "value": 10.345293103448283, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.664879227053145, 10.156979695431469, 10.206122448979581, 10.105707070707073, 10.206607142857138, 10.312963917525774, 9.712572815533985, 10.314355670103096, 10.205255102040814, 10.368290155440423, 9.857093596059103, 10.104343434343434], "value": 10.205255102040814, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.952335766423353, 10.950693430656926, 11.321981132075475, 11.23853932584269, 11.114962962962979, 11.410798479087441, 11.157732342007423, 10.990750915750924, 11.031452205882355, 11.197462686567158, 11.238614232209748, 11.280902255639102], "value": 11.197462686567158, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.472748691099506, 10.929617486338811, 10.756236559139804, 10.874864130434775, 10.58656084656085, 10.81316216216219, 10.756801075268815, 10.473612565445022, 10.476335078534047, 10.757688172043013, 10.816108108108086, 10.582380952380952], "value": 10.756801075268815, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.661286919831218, 12.608130252100842, 12.607310924369752, 12.348641975308647, 12.299467213114752, 12.448506224066406, 12.607773109243704, 12.660147679324902, 12.245591836734686, 12.345781893004121, 12.44910788381743, 12.297684426229516], "value": 12.448506224066406, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.195579268292665, 11.97745508982032, 11.83857988165681, 11.9802994011976, 11.631308139534879, 11.770764705882346, 12.048162650602425, 12.052168674698772, 11.835591715976355, 11.76708823529411, 12.195762195121926, 11.766029411764697], "value": 11.83857988165681, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.586833976833969, 11.280751879699249, 11.585482625482623, 11.496532567049814, 11.36757575757576, 11.767549019607838, 11.282048872180457, 11.236292134831475, 11.239588014981278, 11.451164122137383, 11.409524714828876, 11.49691570881226], "value": 11.409524714828876, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.239887640449421, 10.814324324324332, 11.301666666666689, 10.873885869565209, 10.697085561497305, 11.175837988826807, 10.816405405405385, 11.113027777777743, 10.641489361702158, 11.174078212290523, 10.991236263736257, 10.870380434782616], "value": 10.873885869565209, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.8248500000000005, 6.5114, 6.5305, 6.57165, 6.6697500000000005], "value": 6.551075, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [18253.945000000003, 18809.390000000003, 18541.105, 18437.955, 18449.52], "value": 18495.3125, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
17:04:40 INFO - Return code: 0
17:04:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
17:04:40 INFO - # TBPL SUCCESS #
17:04:40 INFO - Running post-action listener: _package_coverage_data
17:04:40 INFO - Running post-action listener: _resource_record_post_action
17:04:40 INFO - [mozharness: 2017-11-07 01:04:40.240696Z] Finished run-tests step (success)
17:04:40 INFO - Running post-run listener: _resource_record_post_run
17:04:40 INFO - Total resource usage - Wall time: 698s; CPU: 35.0%; Read bytes: 135168; Write bytes: 461213696; Read time: 20; Write time: 295280
17:04:40 INFO - TinderboxPrint: CPU usage
35.4%
17:04:40 INFO - TinderboxPrint: I/O read bytes / time
135,168 / 20
17:04:40 INFO - TinderboxPrint: I/O write bytes / time
461,213,696 / 295,280
17:04:40 INFO - TinderboxPrint: CPU idle
3,547.3 (64.6%)
17:04:40 INFO - TinderboxPrint: CPU system
320.5 (5.8%)
17:04:40 INFO - TinderboxPrint: CPU user
1,601.2 (29.2%)
17:04:40 INFO - TinderboxPrint: Swap in / out
0 / 0
17:04:40 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 62009344; Read time: 0; Write time: 76700
17:04:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
17:04:40 INFO - run-tests - Wall time: 680s; CPU: 36.0%; Read bytes: 135168; Write bytes: 332963840; Read time: 20; Write time: 174168
17:04:40 INFO - Running post-run listener: _upload_blobber_files
17:04:40 INFO - Blob upload gear active.
17:04:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
17:04:40 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
17:04:40 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']
17:04:40 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
17:04:40 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
17:04:40 INFO - SNIMissingWarning
17:04:40 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
17:04:40 INFO - InsecurePlatformWarning
17:04:40 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.)
17:04:40 INFO - SubjectAltNameWarning
17:04:41 INFO - (blobuploader) - INFO - Open directory for files ...
17:04:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
17:04:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:04:41 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:04:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
17:04:41 INFO - InsecurePlatformWarning
17:04:41 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.)
17:04:41 INFO - SubjectAltNameWarning
17:04:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
17:04:41 INFO - InsecurePlatformWarning
17:04:42 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
17:04:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:04:42 INFO - (blobuploader) - INFO - Done attempting.
17:04:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
17:04:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:04:42 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:04:42 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
17:04:42 INFO - InsecurePlatformWarning
17:04:42 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
17:04:42 INFO - SubjectAltNameWarning
17:04:42 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
17:04:42 INFO - InsecurePlatformWarning
17:04:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
17:04:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:04:43 INFO - (blobuploader) - INFO - Done attempting.
17:04:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
17:04:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:04:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:04:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
17:04:43 INFO - InsecurePlatformWarning
17:04:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
17:04:43 INFO - SubjectAltNameWarning
17:04:48 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
17:04:48 INFO - InsecurePlatformWarning
17:04:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
17:04:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:04:48 INFO - (blobuploader) - INFO - Done attempting.
17:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
17:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
17:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
17:04:48 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
17:04:48 INFO - InsecurePlatformWarning
17:04:48 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.)
17:04:48 INFO - SubjectAltNameWarning
17:04:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
17:04:49 INFO - InsecurePlatformWarning
17:04:49 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
17:04:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
17:04:49 INFO - (blobuploader) - INFO - Done attempting.
17:04:49 INFO - (blobuploader) - INFO - Iteration through files over.
17:04:49 INFO - Return code: 0
17:04:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
17:04:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
17:04:49 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc9265b701970966de497ec31a687319d02051e4744dfe9a080c5e0ed777d09067fe16ff17398667f1d2a14e0f53758899f479add33a34f19d21ef535fff11fd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b96e152eddc94280b0d752a1cc4bed1ce6e1478ef09e65fa11b1fcaa6b08738eddd3e60884636e53f78c8ed2977f98e1173b07d33941f75c9259ff74fd1f4311", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88211b7ad72d9b1a741415266ed585d76e252318d55e8d163688680da12d8418fc659ba6d561cbd524742c4dfe54360320e12c7157d1ab9b7007ba1fb165dd9b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9b609b66f376c09770bb0cc93d4e38117a251c3f444c0a2fcfebe60e2c5e2c104e4aa542fb7266134ac8bf7b66c6a6f42f422ea666181a6fdf252bdf9eb073"}
17:04:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
17:04:49 INFO - Writing to file /builds/slave/test/properties/blobber_files
17:04:49 INFO - Contents:
17:04:49 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc9265b701970966de497ec31a687319d02051e4744dfe9a080c5e0ed777d09067fe16ff17398667f1d2a14e0f53758899f479add33a34f19d21ef535fff11fd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b96e152eddc94280b0d752a1cc4bed1ce6e1478ef09e65fa11b1fcaa6b08738eddd3e60884636e53f78c8ed2977f98e1173b07d33941f75c9259ff74fd1f4311", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88211b7ad72d9b1a741415266ed585d76e252318d55e8d163688680da12d8418fc659ba6d561cbd524742c4dfe54360320e12c7157d1ab9b7007ba1fb165dd9b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9b609b66f376c09770bb0cc93d4e38117a251c3f444c0a2fcfebe60e2c5e2c104e4aa542fb7266134ac8bf7b66c6a6f42f422ea666181a6fdf252bdf9eb073"}
17:04:49 INFO - Running post-run listener: copy_logs_to_upload_dir
17:04:49 INFO - Copying logs to upload dir...
17:04:49 INFO - mkdir: /builds/slave/test/build/upload/logs
17:04:49 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=902.737377
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 15 mins, 2 secs) (at 2017-11-06 17:04:49.672079) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-06 17:04:49.675832) =========
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=7de08804244eb36560b44971000001c3-1510015784.830348-724474727
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc9265b701970966de497ec31a687319d02051e4744dfe9a080c5e0ed777d09067fe16ff17398667f1d2a14e0f53758899f479add33a34f19d21ef535fff11fd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b96e152eddc94280b0d752a1cc4bed1ce6e1478ef09e65fa11b1fcaa6b08738eddd3e60884636e53f78c8ed2977f98e1173b07d33941f75c9259ff74fd1f4311", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88211b7ad72d9b1a741415266ed585d76e252318d55e8d163688680da12d8418fc659ba6d561cbd524742c4dfe54360320e12c7157d1ab9b7007ba1fb165dd9b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9b609b66f376c09770bb0cc93d4e38117a251c3f444c0a2fcfebe60e2c5e2c104e4aa542fb7266134ac8bf7b66c6a6f42f422ea666181a6fdf252bdf9eb073"}
build_url:https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011499
build_url: 'https://queue.taskcluster.net/v1/task/E1AZuogaSaekvfF4GFZP9w/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cc9265b701970966de497ec31a687319d02051e4744dfe9a080c5e0ed777d09067fe16ff17398667f1d2a14e0f53758899f479add33a34f19d21ef535fff11fd", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b96e152eddc94280b0d752a1cc4bed1ce6e1478ef09e65fa11b1fcaa6b08738eddd3e60884636e53f78c8ed2977f98e1173b07d33941f75c9259ff74fd1f4311", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88211b7ad72d9b1a741415266ed585d76e252318d55e8d163688680da12d8418fc659ba6d561cbd524742c4dfe54360320e12c7157d1ab9b7007ba1fb165dd9b", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/db9b609b66f376c09770bb0cc93d4e38117a251c3f444c0a2fcfebe60e2c5e2c104e4aa542fb7266134ac8bf7b66c6a6f42f422ea666181a6fdf252bdf9eb073"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-06 17:04:49.706809) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 17:04:49.707192) =========
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=7de08804244eb36560b44971000001c3-1510015784.830348-724474727
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004465
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 17:04:49.788691) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-06 17:04:49.789045) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-06 17:04:49.789395) =========
========= Total master_lag: 0.19 =========