builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-088
starttime: 1511641414.34
results: success (0)
revision: 4c3f754675497ba0b49f3b01d0af6ee523545b8b
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.338238) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.338590) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.338859) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.428000) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.428236) =========
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=9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-25 12:23:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.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% 98.0M=0s
2017-11-25 12:23:34 (98.0 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.092612
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.541712) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.541983) =========
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=9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020108
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:34.598290) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-25 12:23:34.598563) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4c3f754675497ba0b49f3b01d0af6ee523545b8b --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 4c3f754675497ba0b49f3b01d0af6ee523545b8b --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=9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-25 12:23:34,651 truncating revision to first 12 chars
2017-11-25 12:23:34,651 Setting DEBUG logging.
2017-11-25 12:23:34,651 attempt 1/10
2017-11-25 12:23:34,651 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4c3f75467549?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-25 12:23:35,922 unpacking tar archive at: mozilla-central-4c3f75467549/testing/mozharness/
program finished with exit code 0
elapsedTime=1.929685
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-25 12:23:36.547072) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:36.547371) =========
script_repo_revision: 4c3f754675497ba0b49f3b01d0af6ee523545b8b
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:36.547782) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:36.548364) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-25 12:23:36.563173) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 11 secs) (at 2017-11-25 12:23:36.563491) =========
/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=9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
12:23:36 INFO - MultiFileLogger online at 20171125 12:23:36 in /builds/slave/test
12:23:36 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
12:23:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
12:23:36 INFO - {'append_to_log': False,
12:23:36 INFO - 'base_work_dir': '/builds/slave/test',
12:23:36 INFO - 'blob_upload_branch': 'Firefox',
12:23:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
12:23:36 INFO - 'branch': 'Firefox',
12:23:36 INFO - 'buildbot_json_path': 'buildprops.json',
12:23:36 INFO - 'code_coverage': False,
12:23:36 INFO - 'config_files': ('talos/linux_config.py',),
12:23:36 INFO - 'default_actions': ('clobber',
12:23:36 INFO - 'read-buildbot-config',
12:23:36 INFO - 'download-and-extract',
12:23:36 INFO - 'populate-webroot',
12:23:36 INFO - 'create-virtualenv',
12:23:36 INFO - 'install',
12:23:36 INFO - 'setup-mitmproxy',
12:23:36 INFO - 'run-tests'),
12:23:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:23:36 INFO - 'disable_ccov_upload': False,
12:23:36 INFO - 'disable_stylo': False,
12:23:36 INFO - 'download_minidump_stackwalk': True,
12:23:36 INFO - 'download_symbols': 'ondemand',
12:23:36 INFO - 'e10s': False,
12:23:36 INFO - 'enable_stylo': False,
12:23:36 INFO - 'enable_webrender': False,
12:23:36 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
12:23:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:23:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:23:36 INFO - 'gecko_profile': False,
12:23:36 INFO - 'gecko_profile_interval': 0,
12:23:36 INFO - 'installer_path': 'installer.exe',
12:23:36 INFO - 'jsd_code_coverage': False,
12:23:36 INFO - 'log_level': 'info',
12:23:36 INFO - 'log_name': 'talos',
12:23:36 INFO - 'log_to_console': True,
12:23:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
12:23:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
12:23:36 INFO - 'opt_config_files': (),
12:23:36 INFO - 'pip_index': False,
12:23:36 INFO - 'suite': 'g4-e10s',
12:23:36 INFO - 'system_bits': '32',
12:23:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:23:36 INFO - 'title': 'talos-linux64-ix-088',
12:23:36 INFO - 'tooltool_cache': '/builds/tooltool_cache',
12:23:36 INFO - 'use_talos_json': True,
12:23:36 INFO - 'verify': 'False',
12:23:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
12:23:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:23:36 INFO - 'work_dir': 'build'}
12:23:36 INFO - [mozharness: 2017-11-25 20:23:36.752391Z] Running clobber step.
12:23:36 INFO - Running pre-action listener: _resource_record_pre_action
12:23:36 INFO - Running main action method: clobber
12:23:36 INFO - rmtree: /builds/slave/test/build
12:23:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
12:23:37 INFO - Running post-action listener: _resource_record_post_action
12:23:37 INFO - [mozharness: 2017-11-25 20:23:37.836141Z] Finished clobber step (success)
12:23:37 INFO - [mozharness: 2017-11-25 20:23:37.836236Z] Running read-buildbot-config step.
12:23:37 INFO - Running pre-action listener: _resource_record_pre_action
12:23:37 INFO - Running main action method: read_buildbot_config
12:23:37 INFO - Using buildbot properties:
12:23:37 INFO - {
12:23:37 INFO - "project": "",
12:23:37 INFO - "product": "firefox",
12:23:37 INFO - "who": "ffxbld@noreply.mozilla.org",
12:23:37 INFO - "installer_path": "public/build/target.tar.bz2",
12:23:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
12:23:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
12:23:37 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
12:23:37 INFO - "stage_platform": "linux64-qr",
12:23:37 INFO - "basedir": "/builds/slave/test",
12:23:37 INFO - "buildnumber": 235,
12:23:37 INFO - "platform": "ubuntu64_hw_qr",
12:23:37 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
12:23:37 INFO - "slavebuilddir": "test",
12:23:37 INFO - "taskId": "FVRMT_ZdRh-nEnXQDgt_aw",
12:23:37 INFO - "branch": "mozilla-central",
12:23:37 INFO - "script_repo_revision": "production",
12:23:37 INFO - "revision": "4c3f754675497ba0b49f3b01d0af6ee523545b8b",
12:23:37 INFO - "slavename": "talos-linux64-ix-088",
12:23:37 INFO - "repo_path": "mozilla-central"
12:23:37 INFO - }
12:23:37 INFO - Finding installer, test and symbols from parent task.
12:23:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FVRMT_ZdRh-nEnXQDgt_aw'}, attempt #1
12:23:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FVRMT_ZdRh-nEnXQDgt_aw'}, attempt #1
12:23:39 INFO - Task dependencies: GPqOvcFcQOy_dktgrb5WHg
12:23:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg'}, attempt #1
12:23:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2
12:23:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.test_packages.json
12:23:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.crashreporter-symbols.zip
12:23:39 INFO - Running post-action listener: _resource_record_post_action
12:23:39 INFO - [mozharness: 2017-11-25 20:23:39.457229Z] Finished read-buildbot-config step (success)
12:23:39 INFO - [mozharness: 2017-11-25 20:23:39.457531Z] Running download-and-extract step.
12:23:39 INFO - Running pre-action listener: _resource_record_pre_action
12:23:39 INFO - Running main action method: download_and_extract
12:23:39 INFO - mkdir: /builds/slave/test/build/tests
12:23: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')]}
12:23:39 INFO - trying https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.test_packages.json
12:23:39 INFO - Downloading https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
12:23:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
12:23:40 INFO - Downloaded 989 bytes.
12:23:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
12:23:40 INFO - Using the following test package requirements:
12:23:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
12:23:40 INFO - u'common': [u'target.common.tests.zip'],
12:23:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
12:23:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
12:23:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
12:23:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
12:23:40 INFO - u'mozbase': [u'target.common.tests.zip'],
12:23:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
12:23:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
12:23:40 INFO - u'web-platform': [u'target.common.tests.zip',
12:23:40 INFO - u'target.web-platform.tests.tar.gz'],
12:23:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
12:23:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
12:23:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.common.tests.zip
12:23:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.common.tests.zip'}, attempt #1
12:23:40 INFO - Fetch https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.common.tests.zip into memory
12:23:41 INFO - Content-Length response header: 59108958
12:23:41 INFO - Bytes received: 59108958
12:23:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.talos.tests.zip
12:23:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.talos.tests.zip'}, attempt #1
12:23:52 INFO - Fetch https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.talos.tests.zip into memory
12:23:53 INFO - Content-Length response header: 18744811
12:23:53 INFO - Bytes received: 18744811
12:23:53 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')]}
12:23:53 INFO - trying https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2
12:23:53 INFO - Downloading https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
12:23:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
12:23:55 INFO - Downloaded 62656228 bytes.
12:23:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2
12:23:55 INFO - mkdir: /builds/slave/test/properties
12:23:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
12:23:55 INFO - Writing to file /builds/slave/test/properties/build_url
12:23:55 INFO - Contents:
12:23:55 INFO - build_url:https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2
12:23:55 INFO - Running post-action listener: _resource_record_post_action
12:23:55 INFO - Running post-action listener: find_tests_for_verification
12:23:55 INFO - Running post-action listener: set_extra_try_arguments
12:23:55 INFO - [mozharness: 2017-11-25 20:23:55.132387Z] Finished download-and-extract step (success)
12:23:55 INFO - [mozharness: 2017-11-25 20:23:55.132494Z] Running populate-webroot step.
12:23:55 INFO - Running pre-action listener: _resource_record_pre_action
12:23:55 INFO - Running main action method: populate_webroot
12:23:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
12:23:55 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['tresize',
12:23:55 INFO - 'tcanvasmark']},
12:23:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
12:23:55 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['dromaeo_css',
12:23:55 INFO - 'kraken']},
12:23:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:23:55 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:23:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'tests': ['damp', 'tps']},
12:23:55 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['damp', 'tps']},
12:23:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
12:23:55 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['dromaeo_dom']},
12:23:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
12:23:55 INFO - 'glvideo',
12:23:55 INFO - 'displaylist_mutate']},
12:23:55 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['basic_compositor_video',
12:23:55 INFO - 'glvideo']},
12:23:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
12:23:55 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['ts_paint_webext',
12:23:55 INFO - 'tp5o_webext']},
12:23:55 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
12:23:55 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:23:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:23:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:23:55 INFO - 'talos_options': ['--mitmproxy',
12:23:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:23:55 INFO - '--firstNonBlankPaint'],
12:23:55 INFO - 'tests': ['tp6_google_heavy',
12:23:55 INFO - 'tp6_youtube_heavy',
12:23:55 INFO - 'tp6_amazon_heavy',
12:23:55 INFO - 'tp6_facebook_heavy']},
12:23:55 INFO - 'other-e10s': {'tests': ['a11yr',
12:23:55 INFO - 'ts_paint',
12:23:55 INFO - 'tpaint',
12:23:55 INFO - 'sessionrestore',
12:23:55 INFO - 'sessionrestore_many_windows',
12:23:55 INFO - 'sessionrestore_no_auto_restore',
12:23:55 INFO - 'tabpaint',
12:23:55 INFO - 'cpstartup']},
12:23:55 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['a11yr',
12:23:55 INFO - 'ts_paint',
12:23:55 INFO - 'tpaint',
12:23:55 INFO - 'sessionrestore',
12:23:55 INFO - 'sessionrestore_many_windows',
12:23:55 INFO - 'sessionrestore_no_auto_restore',
12:23:55 INFO - 'tabpaint',
12:23:55 INFO - 'cpstartup']},
12:23:55 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
12:23:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
12:23:55 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['perf_reftest_singletons']},
12:23:55 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['perf_reftest']},
12:23:55 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
12:23:55 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['speedometer']},
12:23:55 INFO - 'svgr-e10s': {'tests': ['tsvgx',
12:23:55 INFO - 'tsvgr_opacity',
12:23:55 INFO - 'tart',
12:23:55 INFO - 'tscrollx',
12:23:55 INFO - 'tsvg_static']},
12:23:55 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['tsvgx',
12:23:55 INFO - 'tsvgr_opacity',
12:23:55 INFO - 'tart',
12:23:55 INFO - 'tscrollx',
12:23:55 INFO - 'tsvg_static']},
12:23:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
12:23:55 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'talos_options': ['--disable-stylo'],
12:23:55 INFO - 'tests': ['tp5o']},
12:23:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:23:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:23:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:23:55 INFO - 'talos_options': ['--mitmproxy',
12:23:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:23:55 INFO - '--firstNonBlankPaint'],
12:23:55 INFO - 'tests': ['tp6_google',
12:23:55 INFO - 'tp6_youtube',
12:23:55 INFO - 'tp6_amazon',
12:23:55 INFO - 'tp6_facebook']},
12:23:55 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:23:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:23:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:23:55 INFO - 'talos_options': ['--disable-stylo',
12:23:55 INFO - '--mitmproxy',
12:23:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:23:55 INFO - '--firstNonBlankPaint'],
12:23:55 INFO - 'tests': ['tp6_google',
12:23:55 INFO - 'tp6_youtube',
12:23:55 INFO - 'tp6_amazon',
12:23:55 INFO - 'tp6_facebook']},
12:23:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:23:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:23:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:23:55 INFO - 'talos_options': ['--stylo-threads=1',
12:23:55 INFO - '--mitmproxy',
12:23:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:23:55 INFO - '--firstNonBlankPaint'],
12:23:55 INFO - 'tests': ['tp6_google',
12:23:55 INFO - 'tp6_youtube',
12:23:55 INFO - 'tp6_amazon',
12:23:55 INFO - 'tp6_facebook']},
12:23:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'talos_options': ['--xperf_path',
12:23:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:23:55 INFO - 'tests': ['tp5n']},
12:23:55 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:23:55 INFO - 'talos_options': ['--disable-stylo',
12:23:55 INFO - '--xperf_path',
12:23:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:23:55 INFO - 'tests': ['tp5n']}}}
12:23:55 INFO - Running post-action listener: _resource_record_post_action
12:23:55 INFO - [mozharness: 2017-11-25 20:23:55.152284Z] Finished populate-webroot step (success)
12:23:55 INFO - [mozharness: 2017-11-25 20:23:55.152408Z] Running create-virtualenv step.
12:23:55 INFO - Running pre-action listener: _resource_record_pre_action
12:23:55 INFO - Running main action method: create_virtualenv
12:23:55 INFO - Creating virtualenv /builds/slave/test/build/venv
12:23:55 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
12:23:55 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
12:23:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
12:23:55 INFO - Using real prefix '/usr'
12:23:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python
12:23:55 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
12:23:57 INFO - Installing setuptools, pip, wheel...done.
12:23:57 INFO - Return code: 0
12:23:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
12:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:23:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
12:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
12:23:57 INFO - Using env: {'DISPLAY': ':0',
12:23:57 INFO - 'HOME': '/home/cltbld',
12:23:57 INFO - 'LANG': 'en_US.UTF-8',
12:23:57 INFO - 'LANGUAGE': 'en_US:en',
12:23:57 INFO - 'LOGNAME': 'cltbld',
12:23:57 INFO - 'MAIL': '/var/mail/cltbld',
12:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:23:57 INFO - 'MOZ_NO_REMOTE': '1',
12:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:23:57 INFO - 'NO_EM_RESTART': '1',
12:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:23:57 INFO - 'PWD': '/builds/slave/test',
12:23:57 INFO - 'SHELL': '/bin/bash',
12:23:57 INFO - 'SHLVL': '1',
12:23:57 INFO - 'TERM': 'linux',
12:23:57 INFO - 'TMOUT': '86400',
12:23:57 INFO - 'USER': 'cltbld',
12:23:57 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:23:57 INFO - '_': '/tools/buildbot/bin/python'}
12:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple
12:23:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
12:23:57 INFO - Return code: 0
12:23:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
12:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:23:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
12:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
12:23:57 INFO - Using env: {'DISPLAY': ':0',
12:23:57 INFO - 'HOME': '/home/cltbld',
12:23:57 INFO - 'LANG': 'en_US.UTF-8',
12:23:57 INFO - 'LANGUAGE': 'en_US:en',
12:23:57 INFO - 'LOGNAME': 'cltbld',
12:23:57 INFO - 'MAIL': '/var/mail/cltbld',
12:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:23:57 INFO - 'MOZ_NO_REMOTE': '1',
12:23:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:23:57 INFO - 'NO_EM_RESTART': '1',
12:23:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:23:57 INFO - 'PWD': '/builds/slave/test',
12:23:57 INFO - 'SHELL': '/bin/bash',
12:23:57 INFO - 'SHLVL': '1',
12:23:57 INFO - 'TERM': 'linux',
12:23:57 INFO - 'TMOUT': '86400',
12:23:57 INFO - 'USER': 'cltbld',
12:23:57 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:23:57 INFO - '_': '/tools/buildbot/bin/python'}
12:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple
12:23:58 INFO - Collecting psutil>=3.1.1
12:23:59 INFO - Installing collected packages: psutil
12:23:59 INFO - Successfully installed psutil-3.1.1
12:23:59 INFO - Return code: 0
12:23:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
12:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:23:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:23:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:23:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:23:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
12:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
12:23:59 INFO - Using env: {'DISPLAY': ':0',
12:23:59 INFO - 'HOME': '/home/cltbld',
12:23:59 INFO - 'LANG': 'en_US.UTF-8',
12:23:59 INFO - 'LANGUAGE': 'en_US:en',
12:23:59 INFO - 'LOGNAME': 'cltbld',
12:23:59 INFO - 'MAIL': '/var/mail/cltbld',
12:23:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:23:59 INFO - 'MOZ_NO_REMOTE': '1',
12:23:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:23:59 INFO - 'NO_EM_RESTART': '1',
12:23:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:23:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:23:59 INFO - 'PWD': '/builds/slave/test',
12:23:59 INFO - 'SHELL': '/bin/bash',
12:23:59 INFO - 'SHLVL': '1',
12:23:59 INFO - 'TERM': 'linux',
12:23:59 INFO - 'TMOUT': '86400',
12:23:59 INFO - 'USER': 'cltbld',
12:23:59 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:23:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:23:59 INFO - '_': '/tools/buildbot/bin/python'}
12:23:59 INFO - Ignoring indexes: https://pypi.python.org/simple
12:23:59 INFO - Collecting mozsystemmonitor==0.3
12:24:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
12:24:00 INFO - Installing collected packages: mozsystemmonitor
12:24:00 INFO - Successfully installed mozsystemmonitor-0.3
12:24:00 INFO - Return code: 0
12:24:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
12:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:24:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:24:00 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 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:24:00 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
12:24:00 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
12:24:00 INFO - Using env: {'DISPLAY': ':0',
12:24:00 INFO - 'HOME': '/home/cltbld',
12:24:00 INFO - 'LANG': 'en_US.UTF-8',
12:24:00 INFO - 'LANGUAGE': 'en_US:en',
12:24:00 INFO - 'LOGNAME': 'cltbld',
12:24:00 INFO - 'MAIL': '/var/mail/cltbld',
12:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:00 INFO - 'MOZ_NO_REMOTE': '1',
12:24:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:00 INFO - 'NO_EM_RESTART': '1',
12:24:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:00 INFO - 'PWD': '/builds/slave/test',
12:24:00 INFO - 'SHELL': '/bin/bash',
12:24:00 INFO - 'SHLVL': '1',
12:24:00 INFO - 'TERM': 'linux',
12:24:00 INFO - 'TMOUT': '86400',
12:24:00 INFO - 'USER': 'cltbld',
12:24:00 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:00 INFO - '_': '/tools/buildbot/bin/python'}
12:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple
12:24:01 INFO - Collecting jsonschema==2.5.1
12:24:02 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
12:24:02 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
12:24:03 INFO - Installing collected packages: functools32, jsonschema
12:24:03 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
12:24:03 INFO - Return code: 0
12:24:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
12:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:24:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:24:03 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 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:24:03 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
12:24:03 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
12:24:03 INFO - Using env: {'DISPLAY': ':0',
12:24:03 INFO - 'HOME': '/home/cltbld',
12:24:03 INFO - 'LANG': 'en_US.UTF-8',
12:24:03 INFO - 'LANGUAGE': 'en_US:en',
12:24:03 INFO - 'LOGNAME': 'cltbld',
12:24:03 INFO - 'MAIL': '/var/mail/cltbld',
12:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:03 INFO - 'MOZ_NO_REMOTE': '1',
12:24:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:03 INFO - 'NO_EM_RESTART': '1',
12:24:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:03 INFO - 'PWD': '/builds/slave/test',
12:24:03 INFO - 'SHELL': '/bin/bash',
12:24:03 INFO - 'SHLVL': '1',
12:24:03 INFO - 'TERM': 'linux',
12:24:03 INFO - 'TMOUT': '86400',
12:24:03 INFO - 'USER': 'cltbld',
12:24:03 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:03 INFO - '_': '/tools/buildbot/bin/python'}
12:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple
12:24:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
12:24:04 INFO - Return code: 0
12:24:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
12:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:24:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:24:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
12:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
12:24:04 INFO - Using env: {'DISPLAY': ':0',
12:24:04 INFO - 'HOME': '/home/cltbld',
12:24:04 INFO - 'LANG': 'en_US.UTF-8',
12:24:04 INFO - 'LANGUAGE': 'en_US:en',
12:24:04 INFO - 'LOGNAME': 'cltbld',
12:24:04 INFO - 'MAIL': '/var/mail/cltbld',
12:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:04 INFO - 'MOZ_NO_REMOTE': '1',
12:24:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:04 INFO - 'NO_EM_RESTART': '1',
12:24:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:04 INFO - 'PWD': '/builds/slave/test',
12:24:04 INFO - 'SHELL': '/bin/bash',
12:24:04 INFO - 'SHLVL': '1',
12:24:04 INFO - 'TERM': 'linux',
12:24:04 INFO - 'TMOUT': '86400',
12:24:04 INFO - 'USER': 'cltbld',
12:24:04 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:04 INFO - '_': '/tools/buildbot/bin/python'}
12:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple
12:24:04 INFO - Collecting blobuploader==1.2.4
12:24:05 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
12:24:06 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
12:24:07 INFO - Installing collected packages: requests, docopt, blobuploader
12:24:08 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
12:24:08 INFO - Return code: 0
12:24:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:24:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
12:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:24:08 INFO - Using env: {'DISPLAY': ':0',
12:24:08 INFO - 'HOME': '/home/cltbld',
12:24:08 INFO - 'LANG': 'en_US.UTF-8',
12:24:08 INFO - 'LANGUAGE': 'en_US:en',
12:24:08 INFO - 'LOGNAME': 'cltbld',
12:24:08 INFO - 'MAIL': '/var/mail/cltbld',
12:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:08 INFO - 'MOZ_NO_REMOTE': '1',
12:24:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:08 INFO - 'NO_EM_RESTART': '1',
12:24:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:08 INFO - 'PWD': '/builds/slave/test',
12:24:08 INFO - 'SHELL': '/bin/bash',
12:24:08 INFO - 'SHLVL': '1',
12:24:08 INFO - 'TERM': 'linux',
12:24:08 INFO - 'TMOUT': '86400',
12:24:08 INFO - 'USER': 'cltbld',
12:24:08 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:08 INFO - '_': '/tools/buildbot/bin/python'}
12:24:08 INFO - Ignoring indexes: https://pypi.python.org/simple
12:24:08 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:24:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:24:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:24:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:24:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:24:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:24:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:24:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:24:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:24:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:24:10 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:24:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:24:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:24:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:24:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:24:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:24:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:24:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:24:11 INFO - Running setup.py install for manifestparser: started
12:24:11 INFO - Running setup.py install for manifestparser: finished with status 'done'
12:24:12 INFO - Running setup.py install for mozcrash: started
12:24:12 INFO - Running setup.py install for mozcrash: finished with status 'done'
12:24:12 INFO - Running setup.py install for mozdebug: started
12:24:12 INFO - Running setup.py install for mozdebug: finished with status 'done'
12:24:12 INFO - Running setup.py install for mozdevice: started
12:24:12 INFO - Running setup.py install for mozdevice: finished with status 'done'
12:24:12 INFO - Running setup.py install for mozfile: started
12:24:12 INFO - Running setup.py install for mozfile: finished with status 'done'
12:24:12 INFO - Running setup.py install for mozhttpd: started
12:24:13 INFO - Running setup.py install for mozhttpd: finished with status 'done'
12:24:13 INFO - Running setup.py install for mozinfo: started
12:24:13 INFO - Running setup.py install for mozinfo: finished with status 'done'
12:24:13 INFO - Running setup.py install for mozInstall: started
12:24:13 INFO - Running setup.py install for mozInstall: finished with status 'done'
12:24:13 INFO - Running setup.py install for mozleak: started
12:24:13 INFO - Running setup.py install for mozleak: finished with status 'done'
12:24:13 INFO - Running setup.py install for mozlog: started
12:24:13 INFO - Running setup.py install for mozlog: finished with status 'done'
12:24:13 INFO - Running setup.py install for moznetwork: started
12:24:14 INFO - Running setup.py install for moznetwork: finished with status 'done'
12:24:14 INFO - Running setup.py install for mozprocess: started
12:24:14 INFO - Running setup.py install for mozprocess: finished with status 'done'
12:24:14 INFO - Running setup.py install for mozprofile: started
12:24:14 INFO - Running setup.py install for mozprofile: finished with status 'done'
12:24:14 INFO - Running setup.py install for mozrunner: started
12:24:14 INFO - Running setup.py install for mozrunner: finished with status 'done'
12:24:14 INFO - Running setup.py install for mozscreenshot: started
12:24:15 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
12:24:15 INFO - Running setup.py install for moztest: started
12:24:15 INFO - Running setup.py install for moztest: finished with status 'done'
12:24:15 INFO - Running setup.py install for mozversion: started
12:24:15 INFO - Running setup.py install for mozversion: finished with status 'done'
12:24:15 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
12:24:15 INFO - Return code: 0
12:24:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:24:15 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')]}
12:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:24:15 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')]}
12:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:24:15 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 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:24:15 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
12:24:15 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
12:24:15 INFO - Using env: {'DISPLAY': ':0',
12:24:15 INFO - 'HOME': '/home/cltbld',
12:24:15 INFO - 'LANG': 'en_US.UTF-8',
12:24:15 INFO - 'LANGUAGE': 'en_US:en',
12:24:15 INFO - 'LOGNAME': 'cltbld',
12:24:15 INFO - 'MAIL': '/var/mail/cltbld',
12:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:15 INFO - 'MOZ_NO_REMOTE': '1',
12:24:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:15 INFO - 'NO_EM_RESTART': '1',
12:24:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:15 INFO - 'PWD': '/builds/slave/test',
12:24:15 INFO - 'SHELL': '/bin/bash',
12:24:15 INFO - 'SHLVL': '1',
12:24:15 INFO - 'TERM': 'linux',
12:24:15 INFO - 'TMOUT': '86400',
12:24:15 INFO - 'USER': 'cltbld',
12:24:15 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:15 INFO - '_': '/tools/buildbot/bin/python'}
12:24:16 INFO - Ignoring indexes: https://pypi.python.org/simple
12:24:16 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:24:16 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))
12:24:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:24:16 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))
12:24:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:24:16 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))
12:24:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:24:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
12:24:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:24:17 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))
12:24:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:24:17 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))
12:24:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:24:17 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))
12:24:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:24:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
12:24:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:24:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:24:18 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:24:18 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))
12:24:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:24:18 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))
12:24:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:24:18 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))
12:24:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:24:19 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))
12:24:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:24:19 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))
12:24:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:24:19 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))
12:24:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:24:19 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))
12:24:19 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:24:20 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
12:24:20 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))
12:24:20 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))
12:24:20 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))
12:24:20 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))
12:24:20 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))
12:24:20 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))
12:24:20 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:24:21 INFO - Installing collected packages: six, blessings
12:24:22 INFO - Successfully installed blessings-1.6 six-1.10.0
12:24:22 INFO - Return code: 0
12:24:22 INFO - Done creating virtualenv /builds/slave/test/build/venv.
12:24:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:24:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:24:22 INFO - Reading from file tmpfile_stdout
12:24:22 INFO - Reading from file tmpfile_stderr
12:24:22 INFO - Current package versions:
12:24:22 INFO - blessings == 1.6
12:24:22 INFO - blobuploader == 1.2.4
12:24:22 INFO - docopt == 0.6.1
12:24:22 INFO - functools32 == 3.2.3.post2
12:24:22 INFO - jsonschema == 2.5.1
12:24:22 INFO - manifestparser == 1.2
12:24:22 INFO - mozInstall == 1.14
12:24:22 INFO - mozcrash == 1.0
12:24:22 INFO - mozdebug == 0.1
12:24:22 INFO - mozdevice == 0.51
12:24:22 INFO - mozfile == 1.2
12:24:22 INFO - mozhttpd == 0.7
12:24:22 INFO - mozinfo == 0.10
12:24:22 INFO - mozleak == 0.1
12:24:22 INFO - mozlog == 3.5
12:24:22 INFO - moznetwork == 0.27
12:24:22 INFO - mozprocess == 0.25
12:24:22 INFO - mozprofile == 0.29
12:24:22 INFO - mozrunner == 6.13
12:24:22 INFO - mozscreenshot == 0.1
12:24:22 INFO - mozsystemmonitor == 0.3
12:24:22 INFO - moztest == 0.8
12:24:22 INFO - mozversion == 1.4
12:24:22 INFO - psutil == 3.1.1
12:24:22 INFO - requests == 1.2.3
12:24:22 INFO - six == 1.10.0
12:24:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:24:22 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 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:24:22 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
12:24:22 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
12:24:22 INFO - Using env: {'DISPLAY': ':0',
12:24:22 INFO - 'HOME': '/home/cltbld',
12:24:22 INFO - 'LANG': 'en_US.UTF-8',
12:24:22 INFO - 'LANGUAGE': 'en_US:en',
12:24:22 INFO - 'LOGNAME': 'cltbld',
12:24:22 INFO - 'MAIL': '/var/mail/cltbld',
12:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:22 INFO - 'MOZ_NO_REMOTE': '1',
12:24:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:22 INFO - 'NO_EM_RESTART': '1',
12:24:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:22 INFO - 'PWD': '/builds/slave/test',
12:24:22 INFO - 'SHELL': '/bin/bash',
12:24:22 INFO - 'SHLVL': '1',
12:24:22 INFO - 'TERM': 'linux',
12:24:22 INFO - 'TMOUT': '86400',
12:24:22 INFO - 'USER': 'cltbld',
12:24:22 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:22 INFO - '_': '/tools/buildbot/bin/python'}
12:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
12:24:23 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
12:24:24 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:24:25 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
12:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:24:25 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:24:25 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:24:26 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
12:24:26 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:24:27 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
12:24:27 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:24:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
12:24:28 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:24:29 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
12:24:29 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
12:24:29 INFO - Found existing installation: requests 1.2.3
12:24:29 INFO - Uninstalling requests-1.2.3:
12:24:29 INFO - Successfully uninstalled requests-1.2.3
12:24:30 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
12:24:30 INFO - Return code: 0
12:24:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
12:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:24:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:24:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:24:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2282d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2261a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24031a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, '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': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111', '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
12:24:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
12:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
12:24:30 INFO - Using env: {'DISPLAY': ':0',
12:24:30 INFO - 'HOME': '/home/cltbld',
12:24:30 INFO - 'LANG': 'en_US.UTF-8',
12:24:30 INFO - 'LANGUAGE': 'en_US:en',
12:24:30 INFO - 'LOGNAME': 'cltbld',
12:24:30 INFO - 'MAIL': '/var/mail/cltbld',
12:24:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:30 INFO - 'MOZ_NO_REMOTE': '1',
12:24:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:30 INFO - 'NO_EM_RESTART': '1',
12:24:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:30 INFO - 'PWD': '/builds/slave/test',
12:24:30 INFO - 'SHELL': '/bin/bash',
12:24:30 INFO - 'SHLVL': '1',
12:24:30 INFO - 'TERM': 'linux',
12:24:30 INFO - 'TMOUT': '86400',
12:24:30 INFO - 'USER': 'cltbld',
12:24:30 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:30 INFO - '_': '/tools/buildbot/bin/python'}
12:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple
12:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
12:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
12:24:30 INFO - Return code: 0
12:24:30 INFO - Running post-action listener: _resource_record_post_action
12:24:30 INFO - Running post-action listener: _start_resource_monitoring
12:24:30 INFO - Starting resource monitoring.
12:24:30 INFO - [mozharness: 2017-11-25 20:24:30.649628Z] Finished create-virtualenv step (success)
12:24:30 INFO - [mozharness: 2017-11-25 20:24:30.650094Z] Running install step.
12:24:30 INFO - Running pre-action listener: _resource_record_pre_action
12:24:30 INFO - Running main action method: install
12:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:24:31 INFO - Reading from file tmpfile_stdout
12:24:31 INFO - Reading from file tmpfile_stderr
12:24:31 INFO - Detecting whether we're running mozinstall >=1.0...
12:24:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
12:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
12:24:31 INFO - Reading from file tmpfile_stdout
12:24:31 INFO - Output received:
12:24:31 INFO - Usage: mozinstall [options] installer
12:24:31 INFO - Options:
12:24:31 INFO - -h, --help show this help message and exit
12:24:31 INFO - -d DEST, --destination=DEST
12:24:31 INFO - Directory to install application into. [default:
12:24:31 INFO - "/builds/slave/test"]
12:24:31 INFO - --app=APP Application being installed. [default: firefox]
12:24:31 INFO - mkdir: /builds/slave/test/build/application
12:24:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
12:24:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
12:24:49 INFO - Reading from file tmpfile_stdout
12:24:49 INFO - Output received:
12:24:49 INFO - /builds/slave/test/build/application/firefox/firefox
12:24:49 INFO - Running post-action listener: _resource_record_post_action
12:24:49 INFO - [mozharness: 2017-11-25 20:24:49.659438Z] Finished install step (success)
12:24:49 INFO - [mozharness: 2017-11-25 20:24:49.659580Z] Running setup-mitmproxy step.
12:24:49 INFO - Running pre-action listener: _resource_record_pre_action
12:24:49 INFO - Running main action method: setup_mitmproxy
12:24:49 INFO - Skipping: mitmproxy is not required
12:24:49 INFO - Running post-action listener: _resource_record_post_action
12:24:49 INFO - [mozharness: 2017-11-25 20:24:49.660289Z] Finished setup-mitmproxy step (success)
12:24:49 INFO - [mozharness: 2017-11-25 20:24:49.660505Z] Running run-tests step.
12:24:49 INFO - Running pre-action listener: _resource_record_pre_action
12:24:49 INFO - Running pre-action listener: _set_gcov_prefix
12:24:49 INFO - Running main action method: run_tests
12:24:49 WARNING - Try message not found.
12:24:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
12:24:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
12:24:49 INFO - Python 2.7.3
12:24:49 INFO - Return code: 0
12:24:49 INFO - grabbing minidump binary from tooltool
12:24: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')]}
12:24:49 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 0x2400320>, '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 0x2401220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24016d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
12:24:49 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
12:24:49 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
12:24:49 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
12:24:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
12:24:49 INFO - Return code: 0
12:24:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
12:24:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
12:24:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
12:24:49 INFO - ENV: RUST_BACKTRACE is now full
12:24:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
12:24:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
12:24:49 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/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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
12:24:49 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/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-088 --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
12:24:49 INFO - Using env: {'DISPLAY': ':0',
12:24:49 INFO - 'HOME': '/home/cltbld',
12:24:49 INFO - 'LANG': 'en_US.UTF-8',
12:24:49 INFO - 'LANGUAGE': 'en_US:en',
12:24:49 INFO - 'LOGNAME': 'cltbld',
12:24:49 INFO - 'MAIL': '/var/mail/cltbld',
12:24:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:24:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:24:49 INFO - 'MOZ_ACCELERATED': '1',
12:24:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:24:49 INFO - 'MOZ_NO_REMOTE': '1',
12:24:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:24:49 INFO - 'MOZ_WEBRENDER': '1',
12:24:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:24:49 INFO - 'NO_EM_RESTART': '1',
12:24:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:24:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:24:49 INFO - 'PWD': '/builds/slave/test',
12:24:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:24:49 INFO - 'RUST_BACKTRACE': 'full',
12:24:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:24:49 INFO - 'SHELL': '/bin/bash',
12:24:49 INFO - 'SHLVL': '1',
12:24:49 INFO - 'TERM': 'linux',
12:24:49 INFO - 'TMOUT': '86400',
12:24:49 INFO - 'USER': 'cltbld',
12:24:49 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:24:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:24:49 INFO - '_': '/tools/buildbot/bin/python'}
12:24:49 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/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-088', '--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
12:24:50 INFO - mozversion application_buildid: 20171125193139
12:24:50 INFO - mozversion application_changeset: 4c3f754675497ba0b49f3b01d0af6ee523545b8b
12:24:50 INFO - mozversion application_display_name: Nightly
12:24:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:24:50 INFO - mozversion application_name: Firefox
12:24:50 INFO - mozversion application_remotingname: firefox
12:24:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
12:24:50 INFO - mozversion application_vendor: Mozilla
12:24:50 INFO - mozversion application_version: 59.0a1
12:24:50 INFO - mozversion platform_buildid: 20171125193139
12:24:50 INFO - mozversion platform_changeset: 4c3f754675497ba0b49f3b01d0af6ee523545b8b
12:24:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
12:24:50 INFO - mozversion platform_version: 59.0a1
12:24:50 INFO - using testdate: 1511641490
12:24:50 INFO - actual date: 1511641490
12:24:50 INFO - starting webserver on 'localhost:45835'
12:24:50 INFO - SUITE-START | Running 3 tests
12:24:50 INFO - TEST-START | basic_compositor_video
12:24:50 INFO - Initialising browser for basic_compositor_video test...
12:24:50 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:24:50 INFO - Installing Add-ons
12:24:50 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45835/getInfo.html -profile /tmp/tmpmiFnQ7/profile
12:24:50 INFO - TEST-INFO | started process 24826 (/builds/slave/test/build/application/firefox/firefox http://localhost:45835/getInfo.html)
12:25:00 INFO - TEST-INFO | 24826: exit 0
12:25:00 INFO - Browser initialized.
12:25:00 INFO - Running cycle 1/1 for basic_compositor_video test...
12:25:00 INFO - Using env: {'DISPLAY': ':0',
12:25:00 INFO - 'HOME': '/home/cltbld',
12:25:00 INFO - 'JSGC_DISABLE_POISONING': '1',
12:25:00 INFO - 'LANG': 'en_US.UTF-8',
12:25:00 INFO - 'LANGUAGE': 'en_US:en',
12:25:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:25:00 INFO - 'LOGNAME': 'cltbld',
12:25:00 INFO - 'MAIL': '/var/mail/cltbld',
12:25:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:25:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:25:00 INFO - 'MOZ_ACCELERATED': '1',
12:25:00 INFO - 'MOZ_CRASHREPORTER': '1',
12:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:25:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:25:00 INFO - 'MOZ_NO_REMOTE': '1',
12:25:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:25:00 INFO - 'MOZ_WEBRENDER': '1',
12:25:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:25:00 INFO - 'NO_EM_RESTART': '1',
12:25:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:25:00 INFO - 'PWD': '/builds/slave/test',
12:25:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:25:00 INFO - 'RUST_BACKTRACE': 'full',
12:25:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:25:00 INFO - 'SHELL': '/bin/bash',
12:25:00 INFO - 'SHLVL': '1',
12:25:00 INFO - 'TERM': 'linux',
12:25:00 INFO - 'TMOUT': '86400',
12:25:00 INFO - 'USER': 'cltbld',
12:25:00 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:25:00 INFO - '_': '/tools/buildbot/bin/python'}
12:25:00 INFO - TEST-INFO | started process 25125 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmiFnQ7/profile)
12:25:01 INFO - PID 25125 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:25:01 INFO - PID 25125 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:25:01 INFO - PID 25125 |
12:25:01 INFO - PID 25125 | (/builds/slave/test/build/application/firefox/firefox:25188): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:25:01 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.498218151540383 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.476138613861386 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5295109612141653 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.500875000000001 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0934278350515463 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.072672811059907 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.14392955801105 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.133677685950414 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.226591549295771 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.2030777310924385 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.6954381846635345 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.674357476635514 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.04307511737088 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.758125000000021 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.978011627906973 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7602533783783905 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.010630841121485 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.803401360544233 ms/frame
12:25:47 INFO - PID 25125 |
12:25:47 INFO - PID 25125 | Cycle 1(1): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:25:49 INFO - PID 25125 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:26:33 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.532548523206751 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.469895061728395 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5169840604026845 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5066290726817044 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0900772399588057 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0825192604006166 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.168083333333336 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.124824742268036 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.208120617110799 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.141832298136648 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.673193146417449 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.652093023255809 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.059305882352937 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.780813559322047 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9939277389277485 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758277027027018 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.96163573085846 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.714916107382569 ms/frame
12:26:33 INFO - PID 25125 |
12:26:33 INFO - PID 25125 | Cycle 1(2): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:27:19 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4776919900908343 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.470222222222223 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.525702861952862 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.497958801498128 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.080749486652977 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0782230769230785 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.132871900826449 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.099241803278689 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.305000000000004 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.230190274841432 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.681060842433699 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.6195958429561195 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.977930232558136 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759375000000005 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.01081775700934 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.802976190476206 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.995209790209789 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780220338983054 ms/frame
12:27:19 INFO - PID 25125 |
12:27:19 INFO - PID 25125 | Cycle 1(3): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:28:05 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.492553986710963 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4723238566131034 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.519416456759026 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.506892230576441 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.087294238683126 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0732565284178195 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.184937238493724 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.125206185567015 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.4189764359352 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.416865342163356 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.658959627329197 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.653127906976739 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.009789719626165 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.805425170068046 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.993275058275054 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.804982993197282 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.961461716937354 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.735740740740739 ms/frame
12:28:05 INFO - PID 25125 |
12:28:05 INFO - PID 25125 | Cycle 1(4): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:28:51 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.492113787375415 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4662823674475964 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5217436974789917 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.512858040201005 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.080785420944558 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.081995377503852 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.574611280487807 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.630902777777776 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.580992366412213 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.454632516703788 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.680920436817474 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.663414918414917 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011471962616831 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759020270270264 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.928891454965356 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7567398648648735 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.97854651162791 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.735925925925925 ms/frame
12:28:51 INFO - PID 25125 |
12:28:51 INFO - PID 25125 | Cycle 1(5): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:29:37 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4884452736318408 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.469265432098765 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.509017558528429 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5040550688360446 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.080313141683779 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.07294930875576 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.504902402402402 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.6531046511627885 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.238495762711864 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.2384322033898325 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.733462145110409 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.705764705882355 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.027377049180325 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.758192567567577 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.961276102088162 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758277027027018 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.0266744730679145 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.805765306122438 ms/frame
12:29:37 INFO - PID 25125 |
12:29:37 INFO - PID 25125 | Cycle 1(6): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:30:23 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.47950826446281 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.470395061728395 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5148658843252303 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503304130162703 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.074451844262294 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0678374233128856 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.553019726858875 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.456369710467706 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.20269607843137 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.167270833333335 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.717893081761009 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.706752941176468 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.042464788732386 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.7570777027027065 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.02774004683841 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.75849662162162 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.042347417840378 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.804438775510195 ms/frame
12:30:23 INFO - PID 25125 |
12:30:23 INFO - PID 25125 | Cycle 1(7): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:31:09 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.477469033856317 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4724536464771325 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5210378151260504 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5066478696741847 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0872530864197527 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0723579109063 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.15656509695291 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.158513513513512 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.566727549467275 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.57655606407323 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.6887578125000005 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.685140515222483 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.009813084112151 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.780830508474581 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.0267096018735336 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7808644067796475 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.010280373831779 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.760084459459462 ms/frame
12:31:09 INFO - PID 25125 |
12:31:09 INFO - PID 25125 | Cycle 1(8): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:31:55 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4905228215767634 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.475643564356435 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.510832635983264 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503785982478096 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0804722792607793 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0721428571428575 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.298223495702006 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.2566808510638285 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.546378787878789 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.444811111111113 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.778511146496814 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.786399521531101 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.993310023310017 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.804506802721093 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.009976635514011 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.760084459459462 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.993263403263405 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.802874149659858 ms/frame
12:31:55 INFO - PID 25125 |
12:31:55 INFO - PID 25125 | Cycle 1(9): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:32:41 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.502322768974145 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4704135802469134 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.514694048616932 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5071992481203016 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0837872559095576 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0730184331797235 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.609201228878648 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.662610722610719 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.405337738619677 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.357592592592589 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.854733009708736 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.762630952380953 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.99483682983683 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.757652027027025 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.960661252900228 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.736531986531986 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.9466898148148175 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780033898305087 ms/frame
12:32:41 INFO - PID 25125 |
12:32:41 INFO - PID 25125 | Cycle 1(10): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:33:27 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4864747307373656 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.458255528255529 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5275315922493675 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503366708385481 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0899073120494327 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0820801232665644 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.3165323741007215 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.283822269807277 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.1959860139860155 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.159906444906439 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.731900630914823 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.642679814385149 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.04300469483568 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.827696245733787 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.952928240740743 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.783186440677969 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.9622157772621724 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.758344594594599 ms/frame
12:33:27 INFO - PID 25125 |
12:33:27 INFO - PID 25125 | Cycle 1(11): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:34:13 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4775970272502064 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.475866336633664 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5258627946127947 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.50073125 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0803080082135517 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0684355828220844 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_startup = 4.15073305670816 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1_inclip = 4.167104166666673 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.412838235294117 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.293798283261802 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_startup = 4.666601866251944 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.480p.60fps.webm_scale_2_inclip = 4.67464953271028 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.042370892018786 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.82679180887372 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.962320185614854 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.803537414965979 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.00990654205607 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.757314189189192 ms/frame
12:34:13 INFO - PID 25125 |
12:34:13 INFO - PID 25125 | Cycle 1(12): loaded http://localhost:45835/tests/video/video_playback.html (next: http://localhost:45835/tests/video/video_playback.html)
12:34:14 INFO - PID 25125 | __start_tp_report
12:34:14 INFO - PID 25125 | _x_x_mozilla_page_load
12:34:14 INFO - PID 25125 | _x_x_mozilla_page_load_details
12:34:14 INFO - PID 25125 | |i|pagename|runs|
12:34:14 INFO - PID 25125 | |0;240p.120fps.mp4_scale_1_startup;2.498218151540383;2.532548523206751;2.4776919900908343;2.492553986710963;2.492113787375415;2.4884452736318408;2.47950826446281;2.477469033856317;2.4905228215767634;2.502322768974145;2.4864747307373656;2.4775970272502064
12:34:14 INFO - PID 25125 | |1;240p.120fps.mp4_scale_1_inclip;2.476138613861386;2.469895061728395;2.470222222222223;2.4723238566131034;2.4662823674475964;2.469265432098765;2.470395061728395;2.4724536464771325;2.475643564356435;2.4704135802469134;2.458255528255529;2.475866336633664
12:34:14 INFO - PID 25125 | |2;240p.120fps.mp4_scale_1.1_startup;2.5295109612141653;2.5169840604026845;2.525702861952862;2.519416456759026;2.5217436974789917;2.509017558528429;2.5148658843252303;2.5210378151260504;2.510832635983264;2.514694048616932;2.5275315922493675;2.5258627946127947
12:34:14 INFO - PID 25125 | |3;240p.120fps.mp4_scale_1.1_inclip;2.500875000000001;2.5066290726817044;2.497958801498128;2.506892230576441;2.512858040201005;2.5040550688360446;2.503304130162703;2.5066478696741847;2.503785982478096;2.5071992481203016;2.503366708385481;2.50073125
12:34:14 INFO - PID 25125 | |4;240p.120fps.mp4_scale_2_startup;3.0934278350515463;3.0900772399588057;3.080749486652977;3.087294238683126;3.080785420944558;3.080313141683779;3.074451844262294;3.0872530864197527;3.0804722792607793;3.0837872559095576;3.0899073120494327;3.0803080082135517
12:34:14 INFO - PID 25125 | |5;240p.120fps.mp4_scale_2_inclip;3.072672811059907;3.0825192604006166;3.0782230769230785;3.0732565284178195;3.081995377503852;3.07294930875576;3.0678374233128856;3.0723579109063;3.0721428571428575;3.0730184331797235;3.0820801232665644;3.0684355828220844
12:34:14 INFO - PID 25125 | |6;480p.60fps.webm_scale_1_startup;4.14392955801105;4.168083333333336;4.132871900826449;4.184937238493724;4.574611280487807;4.504902402402402;4.553019726858875;4.15656509695291;4.298223495702006;4.609201228878648;4.3165323741007215;4.15073305670816
12:34:14 INFO - PID 25125 | |7;480p.60fps.webm_scale_1_inclip;4.133677685950414;4.124824742268036;4.099241803278689;4.125206185567015;4.630902777777776;4.6531046511627885;4.456369710467706;4.158513513513512;4.2566808510638285;4.662610722610719;4.283822269807277;4.167104166666673
12:34:14 INFO - PID 25125 | |8;480p.60fps.webm_scale_1.1_startup;4.226591549295771;4.208120617110799;4.305000000000004;4.4189764359352;4.580992366412213;4.238495762711864;4.20269607843137;4.566727549467275;4.546378787878789;4.405337738619677;4.1959860139860155;4.412838235294117
12:34:14 INFO - PID 25125 | |9;480p.60fps.webm_scale_1.1_inclip;4.2030777310924385;4.141832298136648;4.230190274841432;4.416865342163356;4.454632516703788;4.2384322033898325;4.167270833333335;4.57655606407323;4.444811111111113;4.357592592592589;4.159906444906439;4.293798283261802
12:34:14 INFO - PID 25125 | |10;480p.60fps.webm_scale_2_startup;4.6954381846635345;4.673193146417449;4.681060842433699;4.658959627329197;4.680920436817474;4.733462145110409;4.717893081761009;4.6887578125000005;4.778511146496814;4.854733009708736;4.731900630914823;4.666601866251944
12:34:14 INFO - PID 25125 | |11;480p.60fps.webm_scale_2_inclip;4.674357476635514;4.652093023255809;4.6195958429561195;4.653127906976739;4.663414918414917;4.705764705882355;4.706752941176468;4.685140515222483;4.786399521531101;4.762630952380953;4.642679814385149;4.67464953271028
12:34:14 INFO - PID 25125 | |12;1080p.60fps.mp4_scale_1_startup;7.04307511737088;7.059305882352937;6.977930232558136;7.009789719626165;7.011471962616831;7.027377049180325;7.042464788732386;7.009813084112151;6.993310023310017;6.99483682983683;7.04300469483568;7.042370892018786
12:34:14 INFO - PID 25125 | |13;1080p.60fps.mp4_scale_1_inclip;6.758125000000021;6.780813559322047;6.759375000000005;6.805425170068046;6.759020270270264;6.758192567567577;6.7570777027027065;6.780830508474581;6.804506802721093;6.757652027027025;6.827696245733787;6.82679180887372
12:34:14 INFO - PID 25125 | |14;1080p.60fps.mp4_scale_1.1_startup;6.978011627906973;6.9939277389277485;7.01081775700934;6.993275058275054;6.928891454965356;6.961276102088162;7.02774004683841;7.0267096018735336;7.009976635514011;6.960661252900228;6.952928240740743;6.962320185614854
12:34:14 INFO - PID 25125 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.7602533783783905;6.758277027027018;6.802976190476206;6.804982993197282;6.7567398648648735;6.758277027027018;6.75849662162162;6.7808644067796475;6.760084459459462;6.736531986531986;6.783186440677969;6.803537414965979
12:34:14 INFO - PID 25125 | |16;1080p.60fps.mp4_scale_2_startup;7.010630841121485;6.96163573085846;6.995209790209789;6.961461716937354;6.97854651162791;7.0266744730679145;7.042347417840378;7.010280373831779;6.993263403263405;6.9466898148148175;6.9622157772621724;7.00990654205607
12:34:14 INFO - PID 25125 | |17;1080p.60fps.mp4_scale_2_inclip;6.803401360544233;6.714916107382569;6.780220338983054;6.735740740740739;6.735925925925925;6.805765306122438;6.804438775510195;6.760084459459462;6.802874149659858;6.780033898305087;6.758344594594599;6.757314189189192
12:34:14 INFO - PID 25125 | __end_tp_report
12:34:14 INFO - PID 25125 | __start_cc_report
12:34:14 INFO - PID 25125 | _x_x_mozilla_cycle_collect,607
12:34:14 INFO - PID 25125 | __end_cc_report
12:34:14 INFO - PID 25125 | __startTimestamp1511642054008__endTimestamp
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | ------- Summary: start -------
12:34:14 INFO - PID 25125 | Number of tests: 18
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.49 Median:2.49 stddev:0.02 (0.6%) stddev-sans-first:0.02
12:34:14 INFO - PID 25125 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.47 Median:2.47 stddev:0.00 (0.2%) stddev-sans-first:0.00
12:34:14 INFO - PID 25125 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.52 Median:2.52 stddev:0.01 (0.3%) stddev-sans-first:0.01
12:34:14 INFO - PID 25125 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.50 Median:2.51 stddev:0.00 (0.2%) stddev-sans-first:0.00
12:34:14 INFO - PID 25125 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.08 Median:3.09 stddev:0.01 (0.2%) stddev-sans-first:0.00
12:34:14 INFO - PID 25125 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.07 Median:3.07 stddev:0.01 (0.2%) stddev-sans-first:0.01
12:34:14 INFO - PID 25125 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.32 Median:4.31 stddev:0.19 (4.4%) stddev-sans-first:0.19
12:34:14 INFO - PID 25125 | Values: 4.1 4.2 4.1 4.2 4.6 4.5 4.6 4.2 4.3 4.6 4.3 4.2
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.31 Median:4.27 stddev:0.23 (5.3%) stddev-sans-first:0.23
12:34:14 INFO - PID 25125 | Values: 4.1 4.1 4.1 4.1 4.6 4.7 4.5 4.2 4.3 4.7 4.3 4.2
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.36 Median:4.41 stddev:0.15 (3.4%) stddev-sans-first:0.15
12:34:14 INFO - PID 25125 | Values: 4.2 4.2 4.3 4.4 4.6 4.2 4.2 4.6 4.5 4.4 4.2 4.4
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.31 Median:4.33 stddev:0.14 (3.3%) stddev-sans-first:0.14
12:34:14 INFO - PID 25125 | Values: 4.2 4.1 4.2 4.4 4.5 4.2 4.2 4.6 4.4 4.4 4.2 4.3
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.71 Median:4.71 stddev:0.06 (1.2%) stddev-sans-first:0.06
12:34:14 INFO - PID 25125 | Values: 4.7 4.7 4.7 4.7 4.7 4.7 4.7 4.7 4.8 4.9 4.7 4.7
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.69 Median:4.68 stddev:0.05 (1.0%) stddev-sans-first:0.05
12:34:14 INFO - PID 25125 | Values: 4.7 4.7 4.6 4.7 4.7 4.7 4.7 4.7 4.8 4.8 4.6 4.7
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.02 Median:7.03 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:34:14 INFO - PID 25125 | Values: 7.0 7.1 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.78 Median:6.78 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:34:14 INFO - PID 25125 | Values: 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:6.98 Median:6.99 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:34:14 INFO - PID 25125 | Values: 7.0 7.0 7.0 7.0 6.9 7.0 7.0 7.0 7.0 7.0 7.0 7.0
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.77 Median:6.77 stddev:0.02 (0.3%) stddev-sans-first:0.02
12:34:14 INFO - PID 25125 | Values: 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.7 6.8 6.8
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:6.99 Median:7.00 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:34:14 INFO - PID 25125 | Values: 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 6.9 7.0 7.0
12:34:14 INFO - PID 25125 |
12:34:14 INFO - PID 25125 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.77 Median:6.78 stddev:0.03 (0.5%) stddev-sans-first:0.03
12:34:14 INFO - PID 25125 | Values: 6.8 6.7 6.8 6.7 6.7 6.8 6.8 6.8 6.8 6.8 6.8 6.8
12:34:14 INFO - PID 25125 | -------- Summary: end --------
12:34:14 INFO - PID 25125 |
12:34:19 INFO - TEST-INFO | 25125: exit 0
12:34:19 INFO - TEST-OK | basic_compositor_video | took 569852ms
12:34:19 INFO - TEST-START | glvideo
12:34:19 INFO - Initialising browser for glvideo test...
12:34:19 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:34:19 INFO - Installing Add-ons
12:34:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45835/getInfo.html -profile /tmp/tmpMBq5FD/profile
12:34:19 INFO - TEST-INFO | started process 26388 (/builds/slave/test/build/application/firefox/firefox http://localhost:45835/getInfo.html)
12:34:31 INFO - TEST-INFO | 26388: exit 0
12:34:31 INFO - Browser initialized.
12:34:31 INFO - Running cycle 1/1 for glvideo test...
12:34:31 INFO - Using env: {'DISPLAY': ':0',
12:34:31 INFO - 'HOME': '/home/cltbld',
12:34:31 INFO - 'JSGC_DISABLE_POISONING': '1',
12:34:31 INFO - 'LANG': 'en_US.UTF-8',
12:34:31 INFO - 'LANGUAGE': 'en_US:en',
12:34:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:34:31 INFO - 'LOGNAME': 'cltbld',
12:34:31 INFO - 'MAIL': '/var/mail/cltbld',
12:34:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:34:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:34:31 INFO - 'MOZ_ACCELERATED': '1',
12:34:31 INFO - 'MOZ_CRASHREPORTER': '1',
12:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:34:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:34:31 INFO - 'MOZ_NO_REMOTE': '1',
12:34:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:34:31 INFO - 'MOZ_WEBRENDER': '1',
12:34:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:34:31 INFO - 'NO_EM_RESTART': '1',
12:34:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:34:31 INFO - 'PWD': '/builds/slave/test',
12:34:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:34:31 INFO - 'RUST_BACKTRACE': 'full',
12:34:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:34:31 INFO - 'SHELL': '/bin/bash',
12:34:31 INFO - 'SHLVL': '1',
12:34:31 INFO - 'TERM': 'linux',
12:34:31 INFO - 'TMOUT': '86400',
12:34:31 INFO - 'USER': 'cltbld',
12:34:31 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:34:31 INFO - '_': '/tools/buildbot/bin/python'}
12:34:31 INFO - TEST-INFO | started process 26699 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMBq5FD/profile)
12:34:32 INFO - PID 26699 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:32 INFO - PID 26699 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:32 INFO - PID 26699 |
12:34:32 INFO - PID 26699 | (/builds/slave/test/build/application/firefox/firefox:26760): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:34:32 INFO - PID 26699 |
12:34:33 INFO - PID 26699 | [talos glvideo result] Mean tick time across 100 ticks: 6.892 ms
12:34:33 INFO - PID 26699 | Cycle 1(1): loaded http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html)
12:34:34 INFO - PID 26699 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:35 INFO - PID 26699 | [talos glvideo result] Mean tick time across 100 ticks: 6.48645 ms
12:34:35 INFO - PID 26699 | Cycle 1(2): loaded http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html)
12:34:36 INFO - PID 26699 | [talos glvideo result] Mean tick time across 100 ticks: 6.60145 ms
12:34:36 INFO - PID 26699 | Cycle 1(3): loaded http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html)
12:34:38 INFO - PID 26699 | [talos glvideo result] Mean tick time across 100 ticks: 6.68045 ms
12:34:38 INFO - PID 26699 | Cycle 1(4): loaded http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html)
12:34:39 INFO - PID 26699 | [talos glvideo result] Mean tick time across 100 ticks: 6.7010000000000005 ms
12:34:39 INFO - PID 26699 | Cycle 1(5): loaded http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:45835/tests/webgl/benchmarks/video/video_upload.html)
12:34:39 INFO - PID 26699 | __start_tp_report
12:34:39 INFO - PID 26699 | _x_x_mozilla_page_load
12:34:39 INFO - PID 26699 | _x_x_mozilla_page_load_details
12:34:39 INFO - PID 26699 | |i|pagename|runs|
12:34:39 INFO - PID 26699 | |0;Mean tick time across 100 ticks: ;6.892;6.48645;6.60145;6.68045;6.7010000000000005
12:34:39 INFO - PID 26699 | __end_tp_report
12:34:39 INFO - PID 26699 | __start_cc_report
12:34:39 INFO - PID 26699 | _x_x_mozilla_cycle_collect,293
12:34:39 INFO - PID 26699 | __end_cc_report
12:34:39 INFO - PID 26699 | __startTimestamp1511642079594__endTimestamp
12:34:39 INFO - PID 26699 |
12:34:39 INFO - PID 26699 | ------- Summary: start -------
12:34:39 INFO - PID 26699 | Number of tests: 1
12:34:39 INFO - PID 26699 |
12:34:39 INFO - PID 26699 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.67 Median:6.68 stddev:0.15 (2.2%) stddev-sans-first:0.10
12:34:39 INFO - PID 26699 | Values: 6.9 6.5 6.6 6.7 6.7
12:34:39 INFO - PID 26699 | -------- Summary: end --------
12:34:39 INFO - PID 26699 |
12:34:40 INFO - PID 26699 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:34:45 INFO - TEST-INFO | 26699: exit 0
12:34:45 INFO - TEST-OK | glvideo | took 25569ms
12:34:45 INFO - TEST-START | displaylist_mutate
12:34:45 INFO - Initialising browser for displaylist_mutate test...
12:34:45 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:34:45 INFO - Installing Add-ons
12:34:45 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:45835/getInfo.html -profile /tmp/tmphEP9me/profile
12:34:45 INFO - TEST-INFO | started process 26938 (/builds/slave/test/build/application/firefox/firefox http://localhost:45835/getInfo.html)
12:34:56 INFO - TEST-INFO | 26938: exit 0
12:34:56 INFO - Browser initialized.
12:34:56 INFO - Running cycle 1/1 for displaylist_mutate test...
12:34:56 INFO - Using env: {'DISPLAY': ':0',
12:34:56 INFO - 'HOME': '/home/cltbld',
12:34:56 INFO - 'JSGC_DISABLE_POISONING': '1',
12:34:56 INFO - 'LANG': 'en_US.UTF-8',
12:34:56 INFO - 'LANGUAGE': 'en_US:en',
12:34:56 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:34:56 INFO - 'LOGNAME': 'cltbld',
12:34:56 INFO - 'MAIL': '/var/mail/cltbld',
12:34:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:34:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:34:56 INFO - 'MOZ_ACCELERATED': '1',
12:34:56 INFO - 'MOZ_CRASHREPORTER': '1',
12:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:34:56 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:34:56 INFO - 'MOZ_NO_REMOTE': '1',
12:34:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:34:56 INFO - 'MOZ_WEBRENDER': '1',
12:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:34:56 INFO - 'NO_EM_RESTART': '1',
12:34:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:34:56 INFO - 'PWD': '/builds/slave/test',
12:34:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:34:56 INFO - 'RUST_BACKTRACE': 'full',
12:34:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:34:56 INFO - 'SHELL': '/bin/bash',
12:34:56 INFO - 'SHLVL': '1',
12:34:56 INFO - 'TERM': 'linux',
12:34:56 INFO - 'TMOUT': '86400',
12:34:56 INFO - 'USER': 'cltbld',
12:34:56 INFO - 'XDG_SESSION_COOKIE': '9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111',
12:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:34:56 INFO - '_': '/tools/buildbot/bin/python'}
12:34:56 INFO - TEST-INFO | started process 27226 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphEP9me/profile)
12:34:57 INFO - PID 27226 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:57 INFO - PID 27226 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:57 INFO - PID 27226 |
12:34:57 INFO - PID 27226 | (/builds/slave/test/build/application/firefox/firefox:27287): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:34:57 INFO - PID 27226 |
12:35:04 INFO - PID 27226 | Cycle 1(1): loaded http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html)
12:35:04 INFO - PID 27226 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:35:10 INFO - PID 27226 | Cycle 1(2): loaded http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html)
12:35:17 INFO - PID 27226 | Cycle 1(3): loaded http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html)
12:35:24 INFO - PID 27226 | Cycle 1(4): loaded http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html)
12:35:31 INFO - PID 27226 | Cycle 1(5): loaded http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:45835/tests/layout/benchmarks/displaylist_mutate.html)
12:35:32 INFO - PID 27226 | __start_tp_report
12:35:32 INFO - PID 27226 | _x_x_mozilla_page_load
12:35:32 INFO - PID 27226 | _x_x_mozilla_page_load_details
12:35:32 INFO - PID 27226 | |i|pagename|runs|
12:35:32 INFO - PID 27226 | |0;/displaylist_mutate.html;6000.875;6051.08;6235.905;6202.554999999999;6151.24
12:35:32 INFO - PID 27226 | __end_tp_report
12:35:32 INFO - PID 27226 | __start_cc_report
12:35:32 INFO - PID 27226 | _x_x_mozilla_cycle_collect,251
12:35:32 INFO - PID 27226 | __end_cc_report
12:35:32 INFO - PID 27226 | __startTimestamp1511642132135__endTimestamp
12:35:32 INFO - PID 27226 |
12:35:32 INFO - PID 27226 | ------- Summary: start -------
12:35:32 INFO - PID 27226 | Number of tests: 1
12:35:32 INFO - PID 27226 |
12:35:32 INFO - PID 27226 | [#0] /displaylist_mutate.html Cycles:5 Average:6128.33 Median:6151.24 stddev:99.77 (1.6%) stddev-sans-first:80.65
12:35:32 INFO - PID 27226 | Values: 6000.9 6051.1 6235.9 6202.6 6151.2
12:35:32 INFO - PID 27226 | -------- Summary: end --------
12:35:32 INFO - PID 27226 |
12:35:32 INFO - PID 27226 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:35:38 INFO - TEST-INFO | 27226: exit 0
12:35:38 INFO - TEST-OK | displaylist_mutate | took 52610ms
12:35:38 INFO - SUITE-END | took 648s
12:35:38 INFO - Completed test suite (00:10:48)
12:35:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.401335140257879, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.498218151540383, 2.532548523206751, 2.4776919900908343, 2.492553986710963, 2.492113787375415, 2.4884452736318408, 2.47950826446281, 2.477469033856317, 2.4905228215767634, 2.502322768974145, 2.4864747307373656, 2.4775970272502064], "value": 2.4884452736318408, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.476138613861386, 2.469895061728395, 2.470222222222223, 2.4723238566131034, 2.4662823674475964, 2.469265432098765, 2.470395061728395, 2.4724536464771325, 2.475643564356435, 2.4704135802469134, 2.458255528255529, 2.475866336633664], "value": 2.470395061728395, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.5295109612141653, 2.5169840604026845, 2.525702861952862, 2.519416456759026, 2.5217436974789917, 2.509017558528429, 2.5148658843252303, 2.5210378151260504, 2.510832635983264, 2.514694048616932, 2.5275315922493675, 2.5258627946127947], "value": 2.519416456759026, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.500875000000001, 2.5066290726817044, 2.497958801498128, 2.506892230576441, 2.512858040201005, 2.5040550688360446, 2.503304130162703, 2.5066478696741847, 2.503785982478096, 2.5071992481203016, 2.503366708385481, 2.50073125], "value": 2.5040550688360446, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0934278350515463, 3.0900772399588057, 3.080749486652977, 3.087294238683126, 3.080785420944558, 3.080313141683779, 3.074451844262294, 3.0872530864197527, 3.0804722792607793, 3.0837872559095576, 3.0899073120494327, 3.0803080082135517], "value": 3.080785420944558, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.072672811059907, 3.0825192604006166, 3.0782230769230785, 3.0732565284178195, 3.081995377503852, 3.07294930875576, 3.0678374233128856, 3.0723579109063, 3.0721428571428575, 3.0730184331797235, 3.0820801232665644, 3.0684355828220844], "value": 3.0730184331797235, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.14392955801105, 4.168083333333336, 4.132871900826449, 4.184937238493724, 4.574611280487807, 4.504902402402402, 4.553019726858875, 4.15656509695291, 4.298223495702006, 4.609201228878648, 4.3165323741007215, 4.15073305670816], "value": 4.298223495702006, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.133677685950414, 4.124824742268036, 4.099241803278689, 4.125206185567015, 4.630902777777776, 4.6531046511627885, 4.456369710467706, 4.158513513513512, 4.2566808510638285, 4.662610722610719, 4.283822269807277, 4.167104166666673], "value": 4.2566808510638285, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.226591549295771, 4.208120617110799, 4.305000000000004, 4.4189764359352, 4.580992366412213, 4.238495762711864, 4.20269607843137, 4.566727549467275, 4.546378787878789, 4.405337738619677, 4.1959860139860155, 4.412838235294117], "value": 4.405337738619677, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.2030777310924385, 4.141832298136648, 4.230190274841432, 4.416865342163356, 4.454632516703788, 4.2384322033898325, 4.167270833333335, 4.57655606407323, 4.444811111111113, 4.357592592592589, 4.159906444906439, 4.293798283261802], "value": 4.293798283261802, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.6954381846635345, 4.673193146417449, 4.681060842433699, 4.658959627329197, 4.680920436817474, 4.733462145110409, 4.717893081761009, 4.6887578125000005, 4.778511146496814, 4.854733009708736, 4.731900630914823, 4.666601866251944], "value": 4.6887578125000005, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.674357476635514, 4.652093023255809, 4.6195958429561195, 4.653127906976739, 4.663414918414917, 4.705764705882355, 4.706752941176468, 4.685140515222483, 4.786399521531101, 4.762630952380953, 4.642679814385149, 4.67464953271028], "value": 4.67464953271028, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.04307511737088, 7.059305882352937, 6.977930232558136, 7.009789719626165, 7.011471962616831, 7.027377049180325, 7.042464788732386, 7.009813084112151, 6.993310023310017, 6.99483682983683, 7.04300469483568, 7.042370892018786], "value": 7.011471962616831, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.758125000000021, 6.780813559322047, 6.759375000000005, 6.805425170068046, 6.759020270270264, 6.758192567567577, 6.7570777027027065, 6.780830508474581, 6.804506802721093, 6.757652027027025, 6.827696245733787, 6.82679180887372], "value": 6.780813559322047, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.978011627906973, 6.9939277389277485, 7.01081775700934, 6.993275058275054, 6.928891454965356, 6.961276102088162, 7.02774004683841, 7.0267096018735336, 7.009976635514011, 6.960661252900228, 6.952928240740743, 6.962320185614854], "value": 6.993275058275054, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.7602533783783905, 6.758277027027018, 6.802976190476206, 6.804982993197282, 6.7567398648648735, 6.758277027027018, 6.75849662162162, 6.7808644067796475, 6.760084459459462, 6.736531986531986, 6.783186440677969, 6.803537414965979], "value": 6.760084459459462, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.010630841121485, 6.96163573085846, 6.995209790209789, 6.961461716937354, 6.97854651162791, 7.0266744730679145, 7.042347417840378, 7.010280373831779, 6.993263403263405, 6.9466898148148175, 6.9622157772621724, 7.00990654205607], "value": 6.993263403263405, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.803401360544233, 6.714916107382569, 6.780220338983054, 6.735740740740739, 6.735925925925925, 6.805765306122438, 6.804438775510195, 6.760084459459462, 6.802874149659858, 6.780033898305087, 6.758344594594599, 6.757314189189192], "value": 6.760084459459462, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.892, 6.48645, 6.60145, 6.68045, 6.7010000000000005], "value": 6.64095, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6000.875, 6051.08, 6235.905, 6202.554999999999, 6151.24], "value": 6176.897499999999, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
12:35:38 INFO - Return code: 0
12:35:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
12:35:38 INFO - # TBPL SUCCESS #
12:35:38 INFO - Running post-action listener: _package_coverage_data
12:35:38 INFO - Running post-action listener: _resource_record_post_action
12:35:38 INFO - [mozharness: 2017-11-25 20:35:38.685632Z] Finished run-tests step (success)
12:35:38 INFO - Running post-run listener: _resource_record_post_run
12:35:38 INFO - Total resource usage - Wall time: 668s; CPU: 43.0%; Read bytes: 12288; Write bytes: 345616384; Read time: 160; Write time: 278704
12:35:38 INFO - TinderboxPrint: CPU usage
43.2%
12:35:38 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 160
12:35:38 INFO - TinderboxPrint: I/O write bytes / time
345,616,384 / 278,704
12:35:38 INFO - TinderboxPrint: CPU idle
2,834.8 (56.5%)
12:35:38 INFO - TinderboxPrint: CPU system
662.7 (13.2%)
12:35:38 INFO - TinderboxPrint: CPU user
1,497.0 (29.9%)
12:35:38 INFO - TinderboxPrint: Swap in / out
0 / 0
12:35:38 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5185536; Read time: 0; Write time: 348
12:35:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
12:35:38 INFO - run-tests - Wall time: 649s; CPU: 44.0%; Read bytes: 12288; Write bytes: 339152896; Read time: 160; Write time: 278132
12:35:39 INFO - Running post-run listener: _upload_blobber_files
12:35:39 INFO - Blob upload gear active.
12:35:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
12:35:39 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
12:35:39 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']
12:35:39 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
12:35:39 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
12:35:39 INFO - SNIMissingWarning
12:35:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:39 INFO - InsecurePlatformWarning
12:35:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:35:39 INFO - SubjectAltNameWarning
12:35:39 INFO - (blobuploader) - INFO - Open directory for files ...
12:35:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
12:35:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:35:39 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:35:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:39 INFO - InsecurePlatformWarning
12:35:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:35:39 INFO - SubjectAltNameWarning
12:35:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:44 INFO - InsecurePlatformWarning
12:35:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:35:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:35:44 INFO - (blobuploader) - INFO - Done attempting.
12:35:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
12:35:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:35:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:35:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:44 INFO - InsecurePlatformWarning
12:35:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:35:44 INFO - SubjectAltNameWarning
12:35:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:45 INFO - InsecurePlatformWarning
12:35:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
12:35:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:35:45 INFO - (blobuploader) - INFO - Done attempting.
12:35:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
12:35:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:35:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:35:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:45 INFO - InsecurePlatformWarning
12:35:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:35:45 INFO - SubjectAltNameWarning
12:35:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:46 INFO - InsecurePlatformWarning
12:35:46 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
12:35:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:35:46 INFO - (blobuploader) - INFO - Done attempting.
12:35:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
12:35:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:35:46 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:35:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:46 INFO - InsecurePlatformWarning
12:35:46 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:35:46 INFO - SubjectAltNameWarning
12:35:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:35:47 INFO - InsecurePlatformWarning
12:35:47 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
12:35:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:35:47 INFO - (blobuploader) - INFO - Done attempting.
12:35:47 INFO - (blobuploader) - INFO - Iteration through files over.
12:35:47 INFO - Return code: 0
12:35:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
12:35:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
12:35:47 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2917a300c4e791e4976fb3d1b44b9262f3acdd0da1d7cb632cbf9bdadfb79c61209e60186258700424e345d35ea4f9f60e528d7813efb7c6d5a49b30e7c33b3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2848c67ac8cf373b5707b0e7529a709acd8baa3768252ca5aaf3d51556c94c7966105cfe7eb1a39b0bfbe9bc5e0db741624c505e3a88a7c36a74dd86e6752b6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25eee4a0c4073a646bd2f1f58e1fb914b9f9787c44c89f85c7e6c0e7f77d2d72c7ad10b2240d2533b55c79a32102296c98b0734c954edfb88bd3211a7e1981a7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/def8a888ab279252aa981979aecd1035fd793f6ea8414059ec8de8049f45dc1aa3046edc5bc0ba0906e68ccb5a145dea760263f3cefe883bac1d2362816b829c"}
12:35:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
12:35:47 INFO - Writing to file /builds/slave/test/properties/blobber_files
12:35:47 INFO - Contents:
12:35:47 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2917a300c4e791e4976fb3d1b44b9262f3acdd0da1d7cb632cbf9bdadfb79c61209e60186258700424e345d35ea4f9f60e528d7813efb7c6d5a49b30e7c33b3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2848c67ac8cf373b5707b0e7529a709acd8baa3768252ca5aaf3d51556c94c7966105cfe7eb1a39b0bfbe9bc5e0db741624c505e3a88a7c36a74dd86e6752b6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25eee4a0c4073a646bd2f1f58e1fb914b9f9787c44c89f85c7e6c0e7f77d2d72c7ad10b2240d2533b55c79a32102296c98b0734c954edfb88bd3211a7e1981a7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/def8a888ab279252aa981979aecd1035fd793f6ea8414059ec8de8049f45dc1aa3046edc5bc0ba0906e68ccb5a145dea760263f3cefe883bac1d2362816b829c"}
12:35:47 INFO - Running post-run listener: copy_logs_to_upload_dir
12:35:47 INFO - Copying logs to upload dir...
12:35:47 INFO - mkdir: /builds/slave/test/build/upload/logs
12:35:47 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=731.031337
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 11 secs) (at 2017-11-25 12:35:47.628412) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-25 12:35:47.632052) =========
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=9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111
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/d2917a300c4e791e4976fb3d1b44b9262f3acdd0da1d7cb632cbf9bdadfb79c61209e60186258700424e345d35ea4f9f60e528d7813efb7c6d5a49b30e7c33b3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2848c67ac8cf373b5707b0e7529a709acd8baa3768252ca5aaf3d51556c94c7966105cfe7eb1a39b0bfbe9bc5e0db741624c505e3a88a7c36a74dd86e6752b6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25eee4a0c4073a646bd2f1f58e1fb914b9f9787c44c89f85c7e6c0e7f77d2d72c7ad10b2240d2533b55c79a32102296c98b0734c954edfb88bd3211a7e1981a7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/def8a888ab279252aa981979aecd1035fd793f6ea8414059ec8de8049f45dc1aa3046edc5bc0ba0906e68ccb5a145dea760263f3cefe883bac1d2362816b829c"}
build_url:https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011502
build_url: 'https://queue.taskcluster.net/v1/task/GPqOvcFcQOy_dktgrb5WHg/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2917a300c4e791e4976fb3d1b44b9262f3acdd0da1d7cb632cbf9bdadfb79c61209e60186258700424e345d35ea4f9f60e528d7813efb7c6d5a49b30e7c33b3", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2848c67ac8cf373b5707b0e7529a709acd8baa3768252ca5aaf3d51556c94c7966105cfe7eb1a39b0bfbe9bc5e0db741624c505e3a88a7c36a74dd86e6752b6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/25eee4a0c4073a646bd2f1f58e1fb914b9f9787c44c89f85c7e6c0e7f77d2d72c7ad10b2240d2533b55c79a32102296c98b0734c954edfb88bd3211a7e1981a7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/def8a888ab279252aa981979aecd1035fd793f6ea8414059ec8de8049f45dc1aa3046edc5bc0ba0906e68ccb5a145dea760263f3cefe883bac1d2362816b829c"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-25 12:35:47.662576) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 12:35:47.662958) =========
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=9f7544b6a75ada7b6b576032000001a7-1511640176.188334-686582111
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004760
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 12:35:47.746731) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-25 12:35:47.747055) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-25 12:35:47.747345) =========
========= Total master_lag: 0.21 =========