builder: mozilla-central_ubuntu64_hw_test-g4-e10s-pgo
slave: talos-linux64-ix-032
starttime: 1509969694.2
results: success (0)
revision: 8b9167b8a937e09f6673d4d7d693c6ac1c25a3d7
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.195451) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.195902) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.196218) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.219142) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.219552) =========
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=745a4373a2a16df560f216e000000264-1509969692.853719-1173634263
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-06 04:01: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% 24.6M=0s
2017-11-06 04:01:34 (24.6 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.086476
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.326351) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.326736) =========
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=745a4373a2a16df560f216e000000264-1509969692.853719-1173634263
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.021426
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:34.380190) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-06 04:01:34.380568) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8b9167b8a937e09f6673d4d7d693c6ac1c25a3d7 --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 8b9167b8a937e09f6673d4d7d693c6ac1c25a3d7 --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=745a4373a2a16df560f216e000000264-1509969692.853719-1173634263
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-06 04:01:34,522 truncating revision to first 12 chars
2017-11-06 04:01:34,522 Setting DEBUG logging.
2017-11-06 04:01:34,522 attempt 1/10
2017-11-06 04:01:34,522 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8b9167b8a937?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-06 04:01:34,890 unpacking tar archive at: mozilla-central-8b9167b8a937/testing/mozharness/
program finished with exit code 0
elapsedTime=1.082889
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-06 04:01:35.483665) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:35.484087) =========
script_repo_revision: 8b9167b8a937e09f6673d4d7d693c6ac1c25a3d7
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:35.484511) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:35.484805) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-06 04:01:35.505626) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 46 secs) (at 2017-11-06 04:01:35.506075) =========
/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=745a4373a2a16df560f216e000000264-1509969692.853719-1173634263
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
04:01:35 INFO - MultiFileLogger online at 20171106 04:01:35 in /builds/slave/test
04:01:35 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
04:01:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
04:01:35 INFO - {'append_to_log': False,
04:01:35 INFO - 'base_work_dir': '/builds/slave/test',
04:01:35 INFO - 'blob_upload_branch': 'Firefox',
04:01:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
04:01:35 INFO - 'branch': 'Firefox',
04:01:35 INFO - 'buildbot_json_path': 'buildprops.json',
04:01:35 INFO - 'code_coverage': False,
04:01:35 INFO - 'config_files': ('talos/linux_config.py',),
04:01:35 INFO - 'default_actions': ('clobber',
04:01:35 INFO - 'read-buildbot-config',
04:01:35 INFO - 'download-and-extract',
04:01:35 INFO - 'populate-webroot',
04:01:35 INFO - 'create-virtualenv',
04:01:35 INFO - 'install',
04:01:35 INFO - 'setup-mitmproxy',
04:01:35 INFO - 'run-tests'),
04:01:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
04:01:35 INFO - 'disable_ccov_upload': False,
04:01:35 INFO - 'disable_stylo': False,
04:01:35 INFO - 'download_minidump_stackwalk': True,
04:01:35 INFO - 'download_symbols': 'ondemand',
04:01:35 INFO - 'e10s': False,
04:01:35 INFO - 'enable_stylo': False,
04:01:35 INFO - 'enable_webrender': False,
04:01:35 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
04:01:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
04:01:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
04:01:35 INFO - 'gecko_profile': False,
04:01:35 INFO - 'gecko_profile_interval': 0,
04:01:35 INFO - 'installer_path': 'installer.exe',
04:01:35 INFO - 'jsd_code_coverage': False,
04:01:35 INFO - 'log_level': 'info',
04:01:35 INFO - 'log_name': 'talos',
04:01:35 INFO - 'log_to_console': True,
04:01:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
04:01:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
04:01:35 INFO - 'opt_config_files': (),
04:01:35 INFO - 'pip_index': False,
04:01:35 INFO - 'suite': 'g4-e10s',
04:01:35 INFO - 'system_bits': '32',
04:01:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
04:01:35 INFO - 'title': 'talos-linux64-ix-032',
04:01:35 INFO - 'tooltool_cache': '/builds/tooltool_cache',
04:01:35 INFO - 'use_talos_json': True,
04:01:35 INFO - 'verify': 'False',
04:01:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
04:01:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
04:01:35 INFO - 'work_dir': 'build'}
04:01:35 INFO - [mozharness: 2017-11-06 12:01:35.775595Z] Running clobber step.
04:01:35 INFO - Running pre-action listener: _resource_record_pre_action
04:01:35 INFO - Running main action method: clobber
04:01:35 INFO - rmtree: /builds/slave/test/build
04:01:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
04:01:36 INFO - Running post-action listener: _resource_record_post_action
04:01:36 INFO - [mozharness: 2017-11-06 12:01:36.857791Z] Finished clobber step (success)
04:01:36 INFO - [mozharness: 2017-11-06 12:01:36.857888Z] Running read-buildbot-config step.
04:01:36 INFO - Running pre-action listener: _resource_record_pre_action
04:01:36 INFO - Running main action method: read_buildbot_config
04:01:36 INFO - Using buildbot properties:
04:01:36 INFO - {
04:01:36 INFO - "project": "",
04:01:36 INFO - "product": "firefox",
04:01:36 INFO - "who": "ncsoregi@mozilla.com",
04:01:36 INFO - "installer_path": "public/build/target.tar.bz2",
04:01:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
04:01:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
04:01:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-e10s",
04:01:36 INFO - "stage_platform": "linux64-pgo",
04:01:36 INFO - "basedir": "/builds/slave/test",
04:01:36 INFO - "buildnumber": 116,
04:01:36 INFO - "platform": "ubuntu64_hw",
04:01:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
04:01:36 INFO - "slavebuilddir": "test",
04:01:36 INFO - "taskId": "Wp9jP-aERxqgf5LNpi6qRw",
04:01:36 INFO - "branch": "mozilla-central",
04:01:36 INFO - "script_repo_revision": "production",
04:01:36 INFO - "revision": "8b9167b8a937e09f6673d4d7d693c6ac1c25a3d7",
04:01:36 INFO - "slavename": "talos-linux64-ix-032",
04:01:36 INFO - "repo_path": "mozilla-central"
04:01:36 INFO - }
04:01:36 INFO - Finding installer, test and symbols from parent task.
04:01:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wp9jP-aERxqgf5LNpi6qRw'}, attempt #1
04:01:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Wp9jP-aERxqgf5LNpi6qRw'}, attempt #1
04:01:37 INFO - Task dependencies: L7hcqitnSHakEZCJN1QsWQ
04:01:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ'}, attempt #1
04:01:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2
04:01:38 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.test_packages.json
04:01:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.crashreporter-symbols.zip
04:01:38 INFO - Running post-action listener: _resource_record_post_action
04:01:38 INFO - [mozharness: 2017-11-06 12:01:38.205515Z] Finished read-buildbot-config step (success)
04:01:38 INFO - [mozharness: 2017-11-06 12:01:38.205735Z] Running download-and-extract step.
04:01:38 INFO - Running pre-action listener: _resource_record_pre_action
04:01:38 INFO - Running main action method: download_and_extract
04:01:38 INFO - mkdir: /builds/slave/test/build/tests
04:01:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:01:38 INFO - trying https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.test_packages.json
04:01:38 INFO - Downloading https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
04:01:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
04:01:38 INFO - Downloaded 989 bytes.
04:01:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
04:01:38 INFO - Using the following test package requirements:
04:01:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
04:01:38 INFO - u'common': [u'target.common.tests.zip'],
04:01:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
04:01:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
04:01:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
04:01:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
04:01:38 INFO - u'mozbase': [u'target.common.tests.zip'],
04:01:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
04:01:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
04:01:38 INFO - u'web-platform': [u'target.common.tests.zip',
04:01:38 INFO - u'target.web-platform.tests.tar.gz'],
04:01:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
04:01:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
04:01:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.common.tests.zip
04:01:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
04:01:38 INFO - Fetch https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.common.tests.zip into memory
04:01:39 INFO - Content-Length response header: 58644020
04:01:39 INFO - Bytes received: 58644020
04:01:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.talos.tests.zip
04:01:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
04:01:50 INFO - Fetch https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.talos.tests.zip into memory
04:01:51 INFO - Content-Length response header: 18771919
04:01:51 INFO - Bytes received: 18771919
04:01:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:01:52 INFO - trying https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2
04:01:52 INFO - Downloading https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
04:01:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
04:01:53 INFO - Downloaded 63267641 bytes.
04:01:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2
04:01:53 INFO - mkdir: /builds/slave/test/properties
04:01:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
04:01:53 INFO - Writing to file /builds/slave/test/properties/build_url
04:01:53 INFO - Contents:
04:01:53 INFO - build_url:https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2
04:01:53 INFO - Running post-action listener: _resource_record_post_action
04:01:53 INFO - Running post-action listener: find_tests_for_verification
04:01:53 INFO - Running post-action listener: set_extra_try_arguments
04:01:53 INFO - [mozharness: 2017-11-06 12:01:53.196087Z] Finished download-and-extract step (success)
04:01:53 INFO - [mozharness: 2017-11-06 12:01:53.196192Z] Running populate-webroot step.
04:01:53 INFO - Running pre-action listener: _resource_record_pre_action
04:01:53 INFO - Running main action method: populate_webroot
04:01:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
04:01:53 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['tresize',
04:01:53 INFO - 'tcanvasmark']},
04:01:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
04:01:53 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['dromaeo_css',
04:01:53 INFO - 'kraken']},
04:01:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
04:01:53 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
04:01:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'tests': ['damp', 'tps']},
04:01:53 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['damp', 'tps']},
04:01:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
04:01:53 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['dromaeo_dom']},
04:01:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
04:01:53 INFO - 'glvideo',
04:01:53 INFO - 'displaylist_mutate']},
04:01:53 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['basic_compositor_video',
04:01:53 INFO - 'glvideo']},
04:01:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
04:01:53 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['ts_paint_webext',
04:01:53 INFO - 'tp5o_webext']},
04:01:53 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
04:01:53 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:01:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:01:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:01:53 INFO - 'talos_options': ['--mitmproxy',
04:01:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:01:53 INFO - '--firstNonBlankPaint'],
04:01:53 INFO - 'tests': ['tp6_google_heavy',
04:01:53 INFO - 'tp6_youtube_heavy',
04:01:53 INFO - 'tp6_amazon_heavy',
04:01:53 INFO - 'tp6_facebook_heavy']},
04:01:53 INFO - 'other-e10s': {'tests': ['a11yr',
04:01:53 INFO - 'ts_paint',
04:01:53 INFO - 'tpaint',
04:01:53 INFO - 'sessionrestore',
04:01:53 INFO - 'sessionrestore_many_windows',
04:01:53 INFO - 'sessionrestore_no_auto_restore',
04:01:53 INFO - 'tabpaint',
04:01:53 INFO - 'cpstartup']},
04:01:53 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['a11yr',
04:01:53 INFO - 'ts_paint',
04:01:53 INFO - 'tpaint',
04:01:53 INFO - 'sessionrestore',
04:01:53 INFO - 'sessionrestore_many_windows',
04:01:53 INFO - 'sessionrestore_no_auto_restore',
04:01:53 INFO - 'tabpaint',
04:01:53 INFO - 'cpstartup']},
04:01:53 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
04:01:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
04:01:53 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['perf_reftest_singletons']},
04:01:53 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['perf_reftest']},
04:01:53 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
04:01:53 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['speedometer']},
04:01:53 INFO - 'svgr-e10s': {'tests': ['tsvgx',
04:01:53 INFO - 'tsvgr_opacity',
04:01:53 INFO - 'tart',
04:01:53 INFO - 'tscrollx',
04:01:53 INFO - 'tsvg_static']},
04:01:53 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['tsvgx',
04:01:53 INFO - 'tsvgr_opacity',
04:01:53 INFO - 'tart',
04:01:53 INFO - 'tscrollx',
04:01:53 INFO - 'tsvg_static']},
04:01:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
04:01:53 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'talos_options': ['--disable-stylo'],
04:01:53 INFO - 'tests': ['tp5o']},
04:01:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:01:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:01:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:01:53 INFO - 'talos_options': ['--mitmproxy',
04:01:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:01:53 INFO - '--firstNonBlankPaint'],
04:01:53 INFO - 'tests': ['tp6_google',
04:01:53 INFO - 'tp6_youtube',
04:01:53 INFO - 'tp6_amazon',
04:01:53 INFO - 'tp6_facebook']},
04:01:53 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:01:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:01:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:01:53 INFO - 'talos_options': ['--disable-stylo',
04:01:53 INFO - '--mitmproxy',
04:01:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:01:53 INFO - '--firstNonBlankPaint'],
04:01:53 INFO - 'tests': ['tp6_google',
04:01:53 INFO - 'tp6_youtube',
04:01:53 INFO - 'tp6_amazon',
04:01:53 INFO - 'tp6_facebook']},
04:01:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
04:01:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
04:01:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
04:01:53 INFO - 'talos_options': ['--stylo-threads=1',
04:01:53 INFO - '--mitmproxy',
04:01:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
04:01:53 INFO - '--firstNonBlankPaint'],
04:01:53 INFO - 'tests': ['tp6_google',
04:01:53 INFO - 'tp6_youtube',
04:01:53 INFO - 'tp6_amazon',
04:01:53 INFO - 'tp6_facebook']},
04:01:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'talos_options': ['--xperf_path',
04:01:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
04:01:53 INFO - 'tests': ['tp5n']},
04:01:53 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
04:01:53 INFO - 'talos_options': ['--disable-stylo',
04:01:53 INFO - '--xperf_path',
04:01:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
04:01:53 INFO - 'tests': ['tp5n']}}}
04:01:53 INFO - Running post-action listener: _resource_record_post_action
04:01:53 INFO - [mozharness: 2017-11-06 12:01:53.215945Z] Finished populate-webroot step (success)
04:01:53 INFO - [mozharness: 2017-11-06 12:01:53.216055Z] Running create-virtualenv step.
04:01:53 INFO - Running pre-action listener: _resource_record_pre_action
04:01:53 INFO - Running main action method: create_virtualenv
04:01:53 INFO - Creating virtualenv /builds/slave/test/build/venv
04:01:53 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
04:01:53 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
04:01:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
04:01:53 INFO - Using real prefix '/usr'
04:01:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python
04:01:53 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
04:01:59 INFO - Installing setuptools, pip, wheel...done.
04:01:59 INFO - Return code: 0
04:01:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
04:01: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')]}
04:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:01: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')]}
04:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:01: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', 'pip>=1.5'] in /builds/slave/test/build
04:01: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 pip>=1.5
04:01:59 INFO - Using env: {'DISPLAY': ':0',
04:01:59 INFO - 'HOME': '/home/cltbld',
04:01:59 INFO - 'LANG': 'en_US.UTF-8',
04:01:59 INFO - 'LANGUAGE': 'en_US:en',
04:01:59 INFO - 'LOGNAME': 'cltbld',
04:01:59 INFO - 'MAIL': '/var/mail/cltbld',
04:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:01:59 INFO - 'MOZ_NO_REMOTE': '1',
04:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:01:59 INFO - 'NO_EM_RESTART': '1',
04:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:01:59 INFO - 'PWD': '/builds/slave/test',
04:01:59 INFO - 'SHELL': '/bin/bash',
04:01:59 INFO - 'SHLVL': '1',
04:01:59 INFO - 'TERM': 'linux',
04:01:59 INFO - 'TMOUT': '86400',
04:01:59 INFO - 'USER': 'cltbld',
04:01:59 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:01:59 INFO - '_': '/tools/buildbot/bin/python'}
04:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple
04:01:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
04:01:59 INFO - Return code: 0
04:01:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
04:01: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')]}
04:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:01: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')]}
04:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:01: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', '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 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:01: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', 'psutil>=3.1.1'] in /builds/slave/test/build
04:01: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 psutil>=3.1.1
04:01:59 INFO - Using env: {'DISPLAY': ':0',
04:01:59 INFO - 'HOME': '/home/cltbld',
04:01:59 INFO - 'LANG': 'en_US.UTF-8',
04:01:59 INFO - 'LANGUAGE': 'en_US:en',
04:01:59 INFO - 'LOGNAME': 'cltbld',
04:01:59 INFO - 'MAIL': '/var/mail/cltbld',
04:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:01:59 INFO - 'MOZ_NO_REMOTE': '1',
04:01:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:01:59 INFO - 'NO_EM_RESTART': '1',
04:01:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:01:59 INFO - 'PWD': '/builds/slave/test',
04:01:59 INFO - 'SHELL': '/bin/bash',
04:01:59 INFO - 'SHLVL': '1',
04:01:59 INFO - 'TERM': 'linux',
04:01:59 INFO - 'TMOUT': '86400',
04:01:59 INFO - 'USER': 'cltbld',
04:01:59 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:01:59 INFO - '_': '/tools/buildbot/bin/python'}
04:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:00 INFO - Collecting psutil>=3.1.1
04:02:01 INFO - Installing collected packages: psutil
04:02:01 INFO - Successfully installed psutil-3.1.1
04:02:01 INFO - Return code: 0
04:02:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
04:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
04:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
04:02:01 INFO - Using env: {'DISPLAY': ':0',
04:02:01 INFO - 'HOME': '/home/cltbld',
04:02:01 INFO - 'LANG': 'en_US.UTF-8',
04:02:01 INFO - 'LANGUAGE': 'en_US:en',
04:02:01 INFO - 'LOGNAME': 'cltbld',
04:02:01 INFO - 'MAIL': '/var/mail/cltbld',
04:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:01 INFO - 'MOZ_NO_REMOTE': '1',
04:02:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:01 INFO - 'NO_EM_RESTART': '1',
04:02:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:01 INFO - 'PWD': '/builds/slave/test',
04:02:01 INFO - 'SHELL': '/bin/bash',
04:02:01 INFO - 'SHLVL': '1',
04:02:01 INFO - 'TERM': 'linux',
04:02:01 INFO - 'TMOUT': '86400',
04:02:01 INFO - 'USER': 'cltbld',
04:02:01 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:01 INFO - '_': '/tools/buildbot/bin/python'}
04:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:01 INFO - Collecting mozsystemmonitor==0.3
04:02:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
04:02:02 INFO - Installing collected packages: mozsystemmonitor
04:02:02 INFO - Successfully installed mozsystemmonitor-0.3
04:02:02 INFO - Return code: 0
04:02:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
04:02:02 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')]}
04:02:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:02 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')]}
04:02:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:02 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 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:02 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
04:02:02 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
04:02:02 INFO - Using env: {'DISPLAY': ':0',
04:02:02 INFO - 'HOME': '/home/cltbld',
04:02:02 INFO - 'LANG': 'en_US.UTF-8',
04:02:02 INFO - 'LANGUAGE': 'en_US:en',
04:02:02 INFO - 'LOGNAME': 'cltbld',
04:02:02 INFO - 'MAIL': '/var/mail/cltbld',
04:02:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:02 INFO - 'MOZ_NO_REMOTE': '1',
04:02:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:02 INFO - 'NO_EM_RESTART': '1',
04:02:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:02 INFO - 'PWD': '/builds/slave/test',
04:02:02 INFO - 'SHELL': '/bin/bash',
04:02:02 INFO - 'SHLVL': '1',
04:02:02 INFO - 'TERM': 'linux',
04:02:02 INFO - 'TMOUT': '86400',
04:02:02 INFO - 'USER': 'cltbld',
04:02:02 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:02 INFO - '_': '/tools/buildbot/bin/python'}
04:02:03 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:03 INFO - Collecting jsonschema==2.5.1
04:02:04 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
04:02:04 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
04:02:05 INFO - Installing collected packages: functools32, jsonschema
04:02:05 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
04:02:05 INFO - Return code: 0
04:02:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
04:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:05 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 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:05 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
04:02:05 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
04:02:05 INFO - Using env: {'DISPLAY': ':0',
04:02:05 INFO - 'HOME': '/home/cltbld',
04:02:05 INFO - 'LANG': 'en_US.UTF-8',
04:02:05 INFO - 'LANGUAGE': 'en_US:en',
04:02:05 INFO - 'LOGNAME': 'cltbld',
04:02:05 INFO - 'MAIL': '/var/mail/cltbld',
04:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:05 INFO - 'MOZ_NO_REMOTE': '1',
04:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:05 INFO - 'NO_EM_RESTART': '1',
04:02:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:05 INFO - 'PWD': '/builds/slave/test',
04:02:05 INFO - 'SHELL': '/bin/bash',
04:02:05 INFO - 'SHLVL': '1',
04:02:05 INFO - 'TERM': 'linux',
04:02:05 INFO - 'TMOUT': '86400',
04:02:05 INFO - 'USER': 'cltbld',
04:02:05 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:05 INFO - '_': '/tools/buildbot/bin/python'}
04:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
04:02:05 INFO - Return code: 0
04:02:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
04:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:05 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 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:05 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
04:02:05 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
04:02:05 INFO - Using env: {'DISPLAY': ':0',
04:02:05 INFO - 'HOME': '/home/cltbld',
04:02:05 INFO - 'LANG': 'en_US.UTF-8',
04:02:05 INFO - 'LANGUAGE': 'en_US:en',
04:02:05 INFO - 'LOGNAME': 'cltbld',
04:02:05 INFO - 'MAIL': '/var/mail/cltbld',
04:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:05 INFO - 'MOZ_NO_REMOTE': '1',
04:02:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:05 INFO - 'NO_EM_RESTART': '1',
04:02:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:05 INFO - 'PWD': '/builds/slave/test',
04:02:05 INFO - 'SHELL': '/bin/bash',
04:02:05 INFO - 'SHLVL': '1',
04:02:05 INFO - 'TERM': 'linux',
04:02:05 INFO - 'TMOUT': '86400',
04:02:05 INFO - 'USER': 'cltbld',
04:02:05 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:05 INFO - '_': '/tools/buildbot/bin/python'}
04:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:06 INFO - Collecting blobuploader==1.2.4
04:02:07 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
04:02:08 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
04:02:09 INFO - Installing collected packages: requests, docopt, blobuploader
04:02:09 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
04:02:09 INFO - Return code: 0
04:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:02:09 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')]}
04:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:09 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')]}
04:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:09 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 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:09 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
04:02:09 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
04:02:09 INFO - Using env: {'DISPLAY': ':0',
04:02:09 INFO - 'HOME': '/home/cltbld',
04:02:09 INFO - 'LANG': 'en_US.UTF-8',
04:02:09 INFO - 'LANGUAGE': 'en_US:en',
04:02:09 INFO - 'LOGNAME': 'cltbld',
04:02:09 INFO - 'MAIL': '/var/mail/cltbld',
04:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:09 INFO - 'MOZ_NO_REMOTE': '1',
04:02:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:09 INFO - 'NO_EM_RESTART': '1',
04:02:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:09 INFO - 'PWD': '/builds/slave/test',
04:02:09 INFO - 'SHELL': '/bin/bash',
04:02:09 INFO - 'SHLVL': '1',
04:02:09 INFO - 'TERM': 'linux',
04:02:09 INFO - 'TMOUT': '86400',
04:02:09 INFO - 'USER': 'cltbld',
04:02:09 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:09 INFO - '_': '/tools/buildbot/bin/python'}
04:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:10 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
04:02:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
04:02:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
04:02:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
04:02:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
04:02:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
04:02:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
04:02:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
04:02:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
04:02:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
04:02:12 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
04:02:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
04:02:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
04:02:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
04:02:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
04:02:13 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
04:02:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
04:02:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
04:02:13 INFO - Running setup.py install for manifestparser: started
04:02:13 INFO - Running setup.py install for manifestparser: finished with status 'done'
04:02:13 INFO - Running setup.py install for mozcrash: started
04:02:13 INFO - Running setup.py install for mozcrash: finished with status 'done'
04:02:13 INFO - Running setup.py install for mozdebug: started
04:02:14 INFO - Running setup.py install for mozdebug: finished with status 'done'
04:02:14 INFO - Running setup.py install for mozdevice: started
04:02:14 INFO - Running setup.py install for mozdevice: finished with status 'done'
04:02:14 INFO - Running setup.py install for mozfile: started
04:02:14 INFO - Running setup.py install for mozfile: finished with status 'done'
04:02:14 INFO - Running setup.py install for mozhttpd: started
04:02:14 INFO - Running setup.py install for mozhttpd: finished with status 'done'
04:02:14 INFO - Running setup.py install for mozinfo: started
04:02:14 INFO - Running setup.py install for mozinfo: finished with status 'done'
04:02:14 INFO - Running setup.py install for mozInstall: started
04:02:15 INFO - Running setup.py install for mozInstall: finished with status 'done'
04:02:15 INFO - Running setup.py install for mozleak: started
04:02:15 INFO - Running setup.py install for mozleak: finished with status 'done'
04:02:15 INFO - Running setup.py install for mozlog: started
04:02:15 INFO - Running setup.py install for mozlog: finished with status 'done'
04:02:15 INFO - Running setup.py install for moznetwork: started
04:02:15 INFO - Running setup.py install for moznetwork: finished with status 'done'
04:02:15 INFO - Running setup.py install for mozprocess: started
04:02:15 INFO - Running setup.py install for mozprocess: finished with status 'done'
04:02:15 INFO - Running setup.py install for mozprofile: started
04:02:16 INFO - Running setup.py install for mozprofile: finished with status 'done'
04:02:16 INFO - Running setup.py install for mozrunner: started
04:02:16 INFO - Running setup.py install for mozrunner: finished with status 'done'
04:02:16 INFO - Running setup.py install for mozscreenshot: started
04:02:16 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
04:02:16 INFO - Running setup.py install for moztest: started
04:02:16 INFO - Running setup.py install for moztest: finished with status 'done'
04:02:16 INFO - Running setup.py install for mozversion: started
04:02:17 INFO - Running setup.py install for mozversion: finished with status 'done'
04:02:17 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
04:02:17 INFO - Return code: 0
04:02:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:02:17 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')]}
04:02:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:17 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')]}
04:02:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:17 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 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:17 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
04:02:17 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
04:02:17 INFO - Using env: {'DISPLAY': ':0',
04:02:17 INFO - 'HOME': '/home/cltbld',
04:02:17 INFO - 'LANG': 'en_US.UTF-8',
04:02:17 INFO - 'LANGUAGE': 'en_US:en',
04:02:17 INFO - 'LOGNAME': 'cltbld',
04:02:17 INFO - 'MAIL': '/var/mail/cltbld',
04:02:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:17 INFO - 'MOZ_NO_REMOTE': '1',
04:02:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:17 INFO - 'NO_EM_RESTART': '1',
04:02:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:17 INFO - 'PWD': '/builds/slave/test',
04:02:17 INFO - 'SHELL': '/bin/bash',
04:02:17 INFO - 'SHLVL': '1',
04:02:17 INFO - 'TERM': 'linux',
04:02:17 INFO - 'TMOUT': '86400',
04:02:17 INFO - 'USER': 'cltbld',
04:02:17 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:17 INFO - '_': '/tools/buildbot/bin/python'}
04:02:17 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:17 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
04:02:18 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))
04:02:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
04:02:18 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))
04:02:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
04:02:18 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))
04:02:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
04:02:18 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))
04:02:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
04:02:18 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))
04:02:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
04:02:19 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))
04:02:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
04:02:19 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))
04:02:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
04:02:19 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))
04:02:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
04:02:19 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))
04:02:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
04:02:19 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))
04:02:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
04:02:20 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))
04:02:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
04:02:20 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))
04:02:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
04:02:20 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))
04:02:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
04:02:20 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))
04:02:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
04:02:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
04:02:20 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
04:02:21 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))
04:02:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
04:02:21 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))
04:02:21 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
04:02:22 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
04:02:22 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))
04:02:22 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))
04:02:22 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))
04:02:22 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))
04:02:22 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))
04:02:22 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))
04:02:22 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
04:02:23 INFO - Installing collected packages: six, blessings
04:02:23 INFO - Successfully installed blessings-1.6 six-1.10.0
04:02:23 INFO - Return code: 0
04:02:23 INFO - Done creating virtualenv /builds/slave/test/build/venv.
04:02:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
04:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
04:02:24 INFO - Reading from file tmpfile_stdout
04:02:24 INFO - Reading from file tmpfile_stderr
04:02:24 INFO - Current package versions:
04:02:24 INFO - blessings == 1.6
04:02:24 INFO - blobuploader == 1.2.4
04:02:24 INFO - docopt == 0.6.1
04:02:24 INFO - functools32 == 3.2.3.post2
04:02:24 INFO - jsonschema == 2.5.1
04:02:24 INFO - manifestparser == 1.2
04:02:24 INFO - mozInstall == 1.14
04:02:24 INFO - mozcrash == 1.0
04:02:24 INFO - mozdebug == 0.1
04:02:24 INFO - mozdevice == 0.51
04:02:24 INFO - mozfile == 1.2
04:02:24 INFO - mozhttpd == 0.7
04:02:24 INFO - mozinfo == 0.10
04:02:24 INFO - mozleak == 0.1
04:02:24 INFO - mozlog == 3.5
04:02:24 INFO - moznetwork == 0.27
04:02:24 INFO - mozprocess == 0.25
04:02:24 INFO - mozprofile == 0.29
04:02:24 INFO - mozrunner == 6.13
04:02:24 INFO - mozscreenshot == 0.1
04:02:24 INFO - mozsystemmonitor == 0.3
04:02:24 INFO - moztest == 0.8
04:02:24 INFO - mozversion == 1.4
04:02:24 INFO - psutil == 3.1.1
04:02:24 INFO - requests == 1.2.3
04:02:24 INFO - six == 1.10.0
04:02:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv
04:02:24 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')]}
04:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:24 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')]}
04:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:24 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 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:24 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
04:02:24 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
04:02:24 INFO - Using env: {'DISPLAY': ':0',
04:02:24 INFO - 'HOME': '/home/cltbld',
04:02:24 INFO - 'LANG': 'en_US.UTF-8',
04:02:24 INFO - 'LANGUAGE': 'en_US:en',
04:02:24 INFO - 'LOGNAME': 'cltbld',
04:02:24 INFO - 'MAIL': '/var/mail/cltbld',
04:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:24 INFO - 'MOZ_NO_REMOTE': '1',
04:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:24 INFO - 'NO_EM_RESTART': '1',
04:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:24 INFO - 'PWD': '/builds/slave/test',
04:02:24 INFO - 'SHELL': '/bin/bash',
04:02:24 INFO - 'SHLVL': '1',
04:02:24 INFO - 'TERM': 'linux',
04:02:24 INFO - 'TMOUT': '86400',
04:02:24 INFO - 'USER': 'cltbld',
04:02:24 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:24 INFO - '_': '/tools/buildbot/bin/python'}
04:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:24 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))
04:02:24 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))
04:02:24 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))
04:02:24 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))
04:02:24 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))
04:02:24 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))
04:02:24 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))
04:02:24 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))
04:02:24 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))
04:02:24 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
04:02:26 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
04:02:27 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
04:02:27 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))
04:02:27 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))
04:02:27 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))
04:02:27 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))
04:02:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
04:02:28 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
04:02:29 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
04:02:29 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
04:02:30 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
04:02:30 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
04:02:31 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
04:02:31 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
04:02:31 INFO - Found existing installation: requests 1.2.3
04:02:31 INFO - Uninstalling requests-1.2.3:
04:02:31 INFO - Successfully uninstalled requests-1.2.3
04:02:31 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
04:02:31 INFO - Return code: 0
04:02:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
04:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
04:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
04:02:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26f02d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a69d70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, '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': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263', '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
04:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
04:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
04:02:31 INFO - Using env: {'DISPLAY': ':0',
04:02:31 INFO - 'HOME': '/home/cltbld',
04:02:31 INFO - 'LANG': 'en_US.UTF-8',
04:02:31 INFO - 'LANGUAGE': 'en_US:en',
04:02:31 INFO - 'LOGNAME': 'cltbld',
04:02:31 INFO - 'MAIL': '/var/mail/cltbld',
04:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:31 INFO - 'MOZ_NO_REMOTE': '1',
04:02:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:31 INFO - 'NO_EM_RESTART': '1',
04:02:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:31 INFO - 'PWD': '/builds/slave/test',
04:02:31 INFO - 'SHELL': '/bin/bash',
04:02:31 INFO - 'SHLVL': '1',
04:02:31 INFO - 'TERM': 'linux',
04:02:31 INFO - 'TMOUT': '86400',
04:02:31 INFO - 'USER': 'cltbld',
04:02:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:31 INFO - '_': '/tools/buildbot/bin/python'}
04:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple
04:02:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
04:02:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
04:02:32 INFO - Return code: 0
04:02:32 INFO - Running post-action listener: _resource_record_post_action
04:02:32 INFO - Running post-action listener: _start_resource_monitoring
04:02:32 INFO - Starting resource monitoring.
04:02:32 INFO - [mozharness: 2017-11-06 12:02:32.318530Z] Finished create-virtualenv step (success)
04:02:32 INFO - [mozharness: 2017-11-06 12:02:32.318986Z] Running install step.
04:02:32 INFO - Running pre-action listener: _resource_record_pre_action
04:02:32 INFO - Running main action method: install
04:02:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
04:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
04:02:32 INFO - Reading from file tmpfile_stdout
04:02:32 INFO - Reading from file tmpfile_stderr
04:02:32 INFO - Detecting whether we're running mozinstall >=1.0...
04:02:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
04:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
04:02:33 INFO - Reading from file tmpfile_stdout
04:02:33 INFO - Output received:
04:02:33 INFO - Usage: mozinstall [options] installer
04:02:33 INFO - Options:
04:02:33 INFO - -h, --help show this help message and exit
04:02:33 INFO - -d DEST, --destination=DEST
04:02:33 INFO - Directory to install application into. [default:
04:02:33 INFO - "/builds/slave/test"]
04:02:33 INFO - --app=APP Application being installed. [default: firefox]
04:02:33 INFO - mkdir: /builds/slave/test/build/application
04:02:33 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']
04:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
04:02:51 INFO - Reading from file tmpfile_stdout
04:02:51 INFO - Output received:
04:02:51 INFO - /builds/slave/test/build/application/firefox/firefox
04:02:51 INFO - Running post-action listener: _resource_record_post_action
04:02:51 INFO - [mozharness: 2017-11-06 12:02:51.138932Z] Finished install step (success)
04:02:51 INFO - [mozharness: 2017-11-06 12:02:51.139160Z] Running setup-mitmproxy step.
04:02:51 INFO - Running pre-action listener: _resource_record_pre_action
04:02:51 INFO - Running main action method: setup_mitmproxy
04:02:51 INFO - Skipping: mitmproxy is not required
04:02:51 INFO - Running post-action listener: _resource_record_post_action
04:02:51 INFO - [mozharness: 2017-11-06 12:02:51.139674Z] Finished setup-mitmproxy step (success)
04:02:51 INFO - [mozharness: 2017-11-06 12:02:51.139771Z] Running run-tests step.
04:02:51 INFO - Running pre-action listener: _resource_record_pre_action
04:02:51 INFO - Running pre-action listener: _set_gcov_prefix
04:02:51 INFO - Running main action method: run_tests
04:02:51 WARNING - Try message not found.
04:02:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
04:02:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
04:02:51 INFO - Python 2.7.3
04:02:51 INFO - Return code: 0
04:02:51 INFO - grabbing minidump binary from tooltool
04:02:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
04:02:51 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 0x2a6d070>, '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 0x2a6e080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a682a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
04:02:51 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
04:02:51 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
04:02:51 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
04:02:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
04:02:51 INFO - Return code: 0
04:02:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
04:02:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
04:02:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
04:02:51 INFO - ENV: RUST_BACKTRACE is now full
04:02:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
04:02:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
04:02:51 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/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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
04:02:51 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/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --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
04:02:51 INFO - Using env: {'DISPLAY': ':0',
04:02:51 INFO - 'HOME': '/home/cltbld',
04:02:51 INFO - 'LANG': 'en_US.UTF-8',
04:02:51 INFO - 'LANGUAGE': 'en_US:en',
04:02:51 INFO - 'LOGNAME': 'cltbld',
04:02:51 INFO - 'MAIL': '/var/mail/cltbld',
04:02:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:02:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
04:02:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:51 INFO - 'MOZ_NO_REMOTE': '1',
04:02:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:02:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:51 INFO - 'NO_EM_RESTART': '1',
04:02:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:51 INFO - 'PWD': '/builds/slave/test',
04:02:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:02:51 INFO - 'RUST_BACKTRACE': 'full',
04:02:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
04:02:51 INFO - 'SHELL': '/bin/bash',
04:02:51 INFO - 'SHLVL': '1',
04:02:51 INFO - 'TERM': 'linux',
04:02:51 INFO - 'TMOUT': '86400',
04:02:51 INFO - 'USER': 'cltbld',
04:02:51 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:51 INFO - '_': '/tools/buildbot/bin/python'}
04:02:51 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/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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
04:02:51 INFO - mozversion application_buildid: 20171106103428
04:02:51 INFO - mozversion application_changeset: 8b9167b8a937e09f6673d4d7d693c6ac1c25a3d7
04:02:51 INFO - mozversion application_display_name: Nightly
04:02:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
04:02:51 INFO - mozversion application_name: Firefox
04:02:51 INFO - mozversion application_remotingname: firefox
04:02:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
04:02:51 INFO - mozversion application_vendor: Mozilla
04:02:51 INFO - mozversion application_version: 58.0a1
04:02:51 INFO - mozversion platform_buildid: 20171106103428
04:02:51 INFO - mozversion platform_changeset: 8b9167b8a937e09f6673d4d7d693c6ac1c25a3d7
04:02:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
04:02:51 INFO - mozversion platform_version: 58.0a1
04:02:51 INFO - using testdate: 1509969771
04:02:51 INFO - actual date: 1509969771
04:02:51 INFO - starting webserver on 'localhost:53593'
04:02:51 INFO - SUITE-START | Running 3 tests
04:02:51 INFO - TEST-START | basic_compositor_video
04:02:51 INFO - Initialising browser for basic_compositor_video test...
04:02:51 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53593/getInfo.html -profile /tmp/tmpThSQtR/profile
04:02:51 INFO - TEST-INFO | started process 17044 (/builds/slave/test/build/application/firefox/firefox http://localhost:53593/getInfo.html)
04:02:57 INFO - TEST-INFO | 17044: exit 0
04:02:57 INFO - Browser initialized.
04:02:57 INFO - Running cycle 1/1 for basic_compositor_video test...
04:02:57 INFO - Using env: {'DISPLAY': ':0',
04:02:57 INFO - 'HOME': '/home/cltbld',
04:02:57 INFO - 'JSGC_DISABLE_POISONING': '1',
04:02:57 INFO - 'LANG': 'en_US.UTF-8',
04:02:57 INFO - 'LANGUAGE': 'en_US:en',
04:02:57 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
04:02:57 INFO - 'LOGNAME': 'cltbld',
04:02:57 INFO - 'MAIL': '/var/mail/cltbld',
04:02:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:02:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
04:02:57 INFO - 'MOZ_CRASHREPORTER': '1',
04:02:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:02:57 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
04:02:57 INFO - 'MOZ_NO_REMOTE': '1',
04:02:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:02:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:02:57 INFO - 'NO_EM_RESTART': '1',
04:02:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:02:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:02:57 INFO - 'PWD': '/builds/slave/test',
04:02:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:02:57 INFO - 'RUST_BACKTRACE': 'full',
04:02:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
04:02:57 INFO - 'SHELL': '/bin/bash',
04:02:57 INFO - 'SHLVL': '1',
04:02:57 INFO - 'TERM': 'linux',
04:02:57 INFO - 'TMOUT': '86400',
04:02:57 INFO - 'USER': 'cltbld',
04:02:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:02:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:02:57 INFO - '_': '/tools/buildbot/bin/python'}
04:02:57 INFO - TEST-INFO | started process 17321 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpThSQtR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
04:02:58 INFO - PID 17321 |
04:02:58 INFO - PID 17321 | (/builds/slave/test/build/application/firefox/firefox:17374): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
04:02:58 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686672287802136 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669999999999996 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7225401836968997 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7123244863013705 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 3.78808712121212 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9146771037182027 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.505093457943926 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.378902027027029 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.526151012891345 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.348275401069512 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.000233333333345 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 10.310335051546382 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.0728782287823 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527078947368404 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500666666666682 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97946107784431 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.324660377358477 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.177541899441374 ms/frame
04:03:44 INFO - PID 17321 |
04:03:44 INFO - PID 17321 | Cycle 1(1): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:04:30 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6764944134078212 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710818713450295 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7396695652173915 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7460994764397912 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.172712100139083 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.009679358717435 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.546566193853426 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4020663265306137 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682585227272725 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618469101123593 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.172220338983053 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 9.804705882352941 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.949124087591246 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.695855614973262 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659535864978924 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.978293413173652 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.409942965779477 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991291208791214 ms/frame
04:04:30 INFO - PID 17321 |
04:04:30 INFO - PID 17321 | Cycle 1(2): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:05:16 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6808235294117646 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673291666666672 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.742758420441347 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7111976047904187 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.039037685060564 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.937480314960633 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.505110981308411 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.413156996587032 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.660754716981127 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.435448369565225 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.240682593856656 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 9.853275862068948 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.154814126394054 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.53013157894736 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.295143442622948 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.049578313253031 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.7652156862745 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990439560439572 ms/frame
04:05:16 INFO - PID 17321 |
04:05:16 INFO - PID 17321 | Cycle 1(3): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:06:02 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6856348314606744 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6688740617180988 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7806231454005932 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6907100591715982 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.006655540720962 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9688591269841274 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.484889663182346 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4916579406631785 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715180952380948 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618834269662922 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.754623655913969 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 9.805049019607859 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.952390510948911 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640744680851059 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659852320675114 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.19862804878047 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.454160305343532 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991895604395573 ms/frame
04:06:02 INFO - PID 17321 |
04:06:02 INFO - PID 17321 | Cycle 1(4): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:06:49 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6923970671178792 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6671541666666667 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7457417102966843 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7469737991266374 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.038425302826381 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.009779559118235 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.461447520184543 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3965025466893053 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.79241312741313 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.40674324324324 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.241416382252561 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 10.309716494845356 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.990915750915766 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.586851851851856 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659683544303803 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.836775147928956 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.721855468749993 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992554945054927 ms/frame
04:06:49 INFO - PID 17321 |
04:06:49 INFO - PID 17321 | Cycle 1(5): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:07:35 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6840572390572393 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689741451209343 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7752189349112426 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7368750000000002 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.144447513812155 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.108932238193017 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.514039812646372 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.498304195804194 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.68279356060606 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.4653415300546415 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.102575757575758 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 9.709174757281554 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.032628676470575 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.531763157894755 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.246265306122446 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.838047337278134 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.450591603053436 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.116416666666636 ms/frame
04:07:35 INFO - PID 17321 |
04:07:35 INFO - PID 17321 | Cycle 1(6): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:07:58 INFO - PID 17321 |
04:07:58 INFO - PID 17321 | (/builds/slave/test/build/application/firefox/firefox:17459): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
04:07:58 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6798852183650617 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6725627090301005 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7283899769585256 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.749025349650349 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116502057613169 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.195188679245282 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.5633016627078398 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.508982456140349 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.515229779411764 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.183147668393791 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.42010416666666 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 9.953781094527377 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.076254612546128 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.584523809523802 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.249040816326529 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90684523809523 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584266409266426 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.813567567567539 ms/frame
04:08:21 INFO - PID 17321 |
04:08:21 INFO - PID 17321 | Cycle 1(7): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:09:07 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6809831932773112 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682235195996666 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7474839836924871 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7316926406926412 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8616988416988427 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1674374999999975 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.4569066820276513 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.397173174872668 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.781396917148366 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.668852691218129 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.7539605734767 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 10.003449999999994 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.154293680297402 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639494680851056 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.149797570850202 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.69704678362573 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45398854961832 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.564971098265884 ms/frame
04:09:07 INFO - PID 17321 |
04:09:07 INFO - PID 17321 | Cycle 1(8): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:09:53 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680019596864502 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674874999999998 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7206249999999996 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7453228621291443 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.178586350974928 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.12554639175258 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.4535443037974694 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.372715008431704 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7480747126436835 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.495769230769229 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.63914893617022 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 10.587089947089943 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.07101476014761 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.641223404255296 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.877660944205989 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.122878787878797 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.629496124031022 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113916666666652 ms/frame
04:09:53 INFO - PID 17321 |
04:09:53 INFO - PID 17321 | Cycle 1(9): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:10:39 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681959080717489 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674916666666666 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7703038348082596 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7261432269197587 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.14421270718232 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.090071574642127 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.4414048165137623 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3446153846153877 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.727690839694659 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.556486111111108 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 9.869259868421045 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 9.906163366336642 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.949671532846713 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.472356020942415 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.452240663900406 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.122454545454556 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584613899613903 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.81440540540541 ms/frame
04:10:39 INFO - PID 17321 |
04:10:39 INFO - PID 17321 | Cycle 1(10): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:11:26 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6895101351351351 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6708055091819702 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7579847685998828 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7185352233676976 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.202268907563026 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.167749999999997 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.4288971428571426 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3621512605042008 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715295238095236 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.60495798319328 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.641382978723414 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 9.95557213930348 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.116888888888894 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.642553191489377 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399648760330564 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.905446428571416 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586833976833997 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992115384615358 ms/frame
04:11:26 INFO - PID 17321 |
04:11:26 INFO - PID 17321 | Cycle 1(11): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:12:12 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6812436974789915 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6700626043405682 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.731503173687248 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7522329246935202 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_startup = 4.043780323450136 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.073727087576373 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_startup = 3.505549065420563 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4611072664359805 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.70565589353612 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.482301369863016 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_startup = 10.276335616438352 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.480p.60fps.webm_scale_2_inclip = 10.153071065989842 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.196455223880603 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.64194148936169 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.767425531914899 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.051927710843346 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.197276119402995 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.873559782608698 ms/frame
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | Cycle 1(12): loaded http://localhost:53593/tests/video/video_playback.html (next: http://localhost:53593/tests/video/video_playback.html)
04:12:12 INFO - PID 17321 | __start_tp_report
04:12:12 INFO - PID 17321 | _x_x_mozilla_page_load
04:12:12 INFO - PID 17321 | _x_x_mozilla_page_load_details
04:12:12 INFO - PID 17321 | |i|pagename|runs|
04:12:12 INFO - PID 17321 | |0;240p.120fps.mp4_scale_1_startup;1.686672287802136;1.6764944134078212;1.6808235294117646;1.6856348314606744;1.6923970671178792;1.6840572390572393;1.6798852183650617;1.6809831932773112;1.680019596864502;1.681959080717489;1.6895101351351351;1.6812436974789915
04:12:12 INFO - PID 17321 | |1;240p.120fps.mp4_scale_1_inclip;1.6669999999999996;1.6710818713450295;1.6673291666666672;1.6688740617180988;1.6671541666666667;1.6689741451209343;1.6725627090301005;1.6682235195996666;1.6674874999999998;1.6674916666666666;1.6708055091819702;1.6700626043405682
04:12:12 INFO - PID 17321 | |2;240p.120fps.mp4_scale_1.1_startup;1.7225401836968997;1.7396695652173915;1.742758420441347;1.7806231454005932;1.7457417102966843;1.7752189349112426;1.7283899769585256;1.7474839836924871;1.7206249999999996;1.7703038348082596;1.7579847685998828;1.731503173687248
04:12:12 INFO - PID 17321 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7123244863013705;1.7460994764397912;1.7111976047904187;1.6907100591715982;1.7469737991266374;1.7368750000000002;1.749025349650349;1.7316926406926412;1.7453228621291443;1.7261432269197587;1.7185352233676976;1.7522329246935202
04:12:12 INFO - PID 17321 | |4;240p.120fps.mp4_scale_2_startup;3.78808712121212;4.172712100139083;4.039037685060564;4.006655540720962;4.038425302826381;4.144447513812155;4.116502057613169;3.8616988416988427;4.178586350974928;4.14421270718232;4.202268907563026;4.043780323450136
04:12:12 INFO - PID 17321 | |5;240p.120fps.mp4_scale_2_inclip;3.9146771037182027;4.009679358717435;3.937480314960633;3.9688591269841274;4.009779559118235;4.108932238193017;4.195188679245282;4.1674374999999975;4.12554639175258;4.090071574642127;4.167749999999997;4.073727087576373
04:12:12 INFO - PID 17321 | |6;480p.60fps.webm_scale_1_startup;3.505093457943926;3.546566193853426;3.505110981308411;3.484889663182346;3.461447520184543;3.514039812646372;3.5633016627078398;3.4569066820276513;3.4535443037974694;3.4414048165137623;3.4288971428571426;3.505549065420563
04:12:12 INFO - PID 17321 | |7;480p.60fps.webm_scale_1_inclip;3.378902027027029;3.4020663265306137;3.413156996587032;3.4916579406631785;3.3965025466893053;3.498304195804194;3.508982456140349;3.397173174872668;3.372715008431704;3.3446153846153877;3.3621512605042008;3.4611072664359805
04:12:12 INFO - PID 17321 | |8;480p.60fps.webm_scale_1.1_startup;5.526151012891345;5.682585227272725;5.660754716981127;5.715180952380948;5.79241312741313;5.68279356060606;5.515229779411764;5.781396917148366;5.7480747126436835;5.727690839694659;5.715295238095236;5.70565589353612
04:12:12 INFO - PID 17321 | |9;480p.60fps.webm_scale_1.1_inclip;5.348275401069512;5.618469101123593;5.435448369565225;5.618834269662922;5.40674324324324;5.4653415300546415;5.183147668393791;5.668852691218129;5.495769230769229;5.556486111111108;5.60495798319328;5.482301369863016
04:12:12 INFO - PID 17321 | |10;480p.60fps.webm_scale_2_startup;10.000233333333345;10.172220338983053;10.240682593856656;10.754623655913969;10.241416382252561;10.102575757575758;10.42010416666666;10.7539605734767;10.63914893617022;9.869259868421045;10.641382978723414;10.276335616438352
04:12:12 INFO - PID 17321 | |11;480p.60fps.webm_scale_2_inclip;10.310335051546382;9.804705882352941;9.853275862068948;9.805049019607859;10.309716494845356;9.709174757281554;9.953781094527377;10.003449999999994;10.587089947089943;9.906163366336642;9.95557213930348;10.153071065989842
04:12:12 INFO - PID 17321 | |12;1080p.60fps.mp4_scale_1_startup;11.0728782287823;10.949124087591246;11.154814126394054;10.952390510948911;10.990915750915766;11.032628676470575;11.076254612546128;11.154293680297402;11.07101476014761;10.949671532846713;11.116888888888894;11.196455223880603
04:12:12 INFO - PID 17321 | |13;1080p.60fps.mp4_scale_1_inclip;10.527078947368404;10.695855614973262;10.53013157894736;10.640744680851059;10.586851851851856;10.531763157894755;10.584523809523802;10.639494680851056;10.641223404255296;10.472356020942415;10.642553191489377;10.64194148936169
04:12:12 INFO - PID 17321 | |14;1080p.60fps.mp4_scale_1.1_startup;12.500666666666682;12.659535864978924;12.295143442622948;12.659852320675114;12.659683544303803;12.246265306122446;12.249040816326529;12.149797570850202;12.877660944205989;12.452240663900406;12.399648760330564;12.767425531914899
04:12:12 INFO - PID 17321 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.97946107784431;11.978293413173652;12.049578313253031;12.19862804878047;11.836775147928956;11.838047337278134;11.90684523809523;11.69704678362573;12.122878787878797;12.122454545454556;11.905446428571416;12.051927710843346
04:12:12 INFO - PID 17321 | |16;1080p.60fps.mp4_scale_2_startup;11.324660377358477;11.409942965779477;11.7652156862745;11.454160305343532;11.721855468749993;11.450591603053436;11.584266409266426;11.45398854961832;11.629496124031022;11.584613899613903;11.586833976833997;11.197276119402995
04:12:12 INFO - PID 17321 | |17;1080p.60fps.mp4_scale_2_inclip;11.177541899441374;10.991291208791214;10.990439560439572;10.991895604395573;10.992554945054927;11.116416666666636;10.813567567567539;11.564971098265884;11.113916666666652;10.81440540540541;10.992115384615358;10.873559782608698
04:12:12 INFO - PID 17321 | __end_tp_report
04:12:12 INFO - PID 17321 | __start_cc_report
04:12:12 INFO - PID 17321 | _x_x_mozilla_cycle_collect,557
04:12:12 INFO - PID 17321 | __end_cc_report
04:12:12 INFO - PID 17321 | __startTimestamp1509970332507__endTimestamp
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | ------- Summary: start -------
04:12:12 INFO - PID 17321 | Number of tests: 18
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
04:12:12 INFO - PID 17321 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
04:12:12 INFO - PID 17321 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.75 Median:1.75 stddev:0.02 (1.2%) stddev-sans-first:0.02
04:12:12 INFO - PID 17321 | Values: 1.7 1.7 1.7 1.8 1.7 1.8 1.7 1.7 1.7 1.8 1.8 1.7
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.73 Median:1.74 stddev:0.02 (1.1%) stddev-sans-first:0.02
04:12:12 INFO - PID 17321 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.8
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.06 Median:4.13 stddev:0.13 (3.1%) stddev-sans-first:0.10
04:12:12 INFO - PID 17321 | Values: 3.8 4.2 4.0 4.0 4.0 4.1 4.1 3.9 4.2 4.1 4.2 4.0
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.06 Median:4.10 stddev:0.09 (2.3%) stddev-sans-first:0.09
04:12:12 INFO - PID 17321 | Values: 3.9 4.0 3.9 4.0 4.0 4.1 4.2 4.2 4.1 4.1 4.2 4.1
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.49 Median:3.51 stddev:0.04 (1.2%) stddev-sans-first:0.04
04:12:12 INFO - PID 17321 | Values: 3.5 3.5 3.5 3.5 3.5 3.5 3.6 3.5 3.5 3.4 3.4 3.5
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.41 stddev:0.06 (1.7%) stddev-sans-first:0.06
04:12:12 INFO - PID 17321 | Values: 3.4 3.4 3.4 3.5 3.4 3.5 3.5 3.4 3.4 3.3 3.4 3.5
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.69 Median:5.72 stddev:0.09 (1.5%) stddev-sans-first:0.07
04:12:12 INFO - PID 17321 | Values: 5.5 5.7 5.7 5.7 5.8 5.7 5.5 5.8 5.7 5.7 5.7 5.7
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.49 Median:5.53 stddev:0.14 (2.5%) stddev-sans-first:0.14
04:12:12 INFO - PID 17321 | Values: 5.3 5.6 5.4 5.6 5.4 5.5 5.2 5.7 5.5 5.6 5.6 5.5
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.34 Median:10.53 stddev:0.30 (2.8%) stddev-sans-first:0.29
04:12:12 INFO - PID 17321 | Values: 10.0 10.2 10.2 10.8 10.2 10.1 10.4 10.8 10.6 9.9 10.6 10.3
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.03 Median:9.80 stddev:0.26 (2.7%) stddev-sans-first:0.26
04:12:12 INFO - PID 17321 | Values: 10.3 9.8 9.9 9.8 10.3 9.7 10.0 10.0 10.6 9.9 10.0 10.2
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.06 Median:11.07 stddev:0.09 (0.8%) stddev-sans-first:0.09
04:12:12 INFO - PID 17321 | Values: 11.1 10.9 11.2 11.0 11.0 11.0 11.1 11.2 11.1 10.9 11.1 11.2
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.59 Median:10.64 stddev:0.07 (0.6%) stddev-sans-first:0.07
04:12:12 INFO - PID 17321 | Values: 10.5 10.7 10.5 10.6 10.6 10.5 10.6 10.6 10.6 10.5 10.6 10.6
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.49 Median:12.58 stddev:0.23 (1.8%) stddev-sans-first:0.24
04:12:12 INFO - PID 17321 | Values: 12.5 12.7 12.3 12.7 12.7 12.2 12.2 12.1 12.9 12.5 12.4 12.8
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.97 Median:12.01 stddev:0.14 (1.2%) stddev-sans-first:0.15
04:12:12 INFO - PID 17321 | Values: 12.0 12.0 12.0 12.2 11.8 11.8 11.9 11.7 12.1 12.1 11.9 12.1
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.51 Median:11.58 stddev:0.16 (1.4%) stddev-sans-first:0.16
04:12:12 INFO - PID 17321 | Values: 11.3 11.4 11.8 11.5 11.7 11.5 11.6 11.5 11.6 11.6 11.6 11.2
04:12:12 INFO - PID 17321 |
04:12:12 INFO - PID 17321 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.04 Median:10.99 stddev:0.20 (1.8%) stddev-sans-first:0.21
04:12:12 INFO - PID 17321 | Values: 11.2 11.0 11.0 11.0 11.0 11.1 10.8 11.6 11.1 10.8 11.0 10.9
04:12:12 INFO - PID 17321 | -------- Summary: end --------
04:12:12 INFO - PID 17321 |
04:12:13 INFO - TEST-INFO | 17321: exit 0
04:12:13 INFO - TEST-OK | basic_compositor_video | took 561523ms
04:12:13 INFO - TEST-START | glvideo
04:12:13 INFO - Initialising browser for glvideo test...
04:12:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53593/getInfo.html -profile /tmp/tmpJMWYc4/profile
04:12:13 INFO - TEST-INFO | started process 18625 (/builds/slave/test/build/application/firefox/firefox http://localhost:53593/getInfo.html)
04:12:18 INFO - TEST-INFO | 18625: exit 0
04:12:18 INFO - Browser initialized.
04:12:18 INFO - Running cycle 1/1 for glvideo test...
04:12:18 INFO - Using env: {'DISPLAY': ':0',
04:12:18 INFO - 'HOME': '/home/cltbld',
04:12:18 INFO - 'JSGC_DISABLE_POISONING': '1',
04:12:18 INFO - 'LANG': 'en_US.UTF-8',
04:12:18 INFO - 'LANGUAGE': 'en_US:en',
04:12:18 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
04:12:18 INFO - 'LOGNAME': 'cltbld',
04:12:18 INFO - 'MAIL': '/var/mail/cltbld',
04:12:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:12:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
04:12:18 INFO - 'MOZ_CRASHREPORTER': '1',
04:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:12:18 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
04:12:18 INFO - 'MOZ_NO_REMOTE': '1',
04:12:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:12:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:12:18 INFO - 'NO_EM_RESTART': '1',
04:12:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:12:18 INFO - 'PWD': '/builds/slave/test',
04:12:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:12:18 INFO - 'RUST_BACKTRACE': 'full',
04:12:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
04:12:18 INFO - 'SHELL': '/bin/bash',
04:12:18 INFO - 'SHLVL': '1',
04:12:18 INFO - 'TERM': 'linux',
04:12:18 INFO - 'TMOUT': '86400',
04:12:18 INFO - 'USER': 'cltbld',
04:12:18 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:12:18 INFO - '_': '/tools/buildbot/bin/python'}
04:12:18 INFO - TEST-INFO | started process 18904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJMWYc4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
04:12:19 INFO - PID 18904 |
04:12:19 INFO - PID 18904 | (/builds/slave/test/build/application/firefox/firefox:18957): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
04:12:19 INFO - PID 18904 |
04:12:21 INFO - PID 18904 | [talos glvideo result] Mean tick time across 100 ticks: 9.150549999999999 ms
04:12:21 INFO - PID 18904 | Cycle 1(1): loaded http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html)
04:12:22 INFO - PID 18904 | [talos glvideo result] Mean tick time across 100 ticks: 6.55875 ms
04:12:22 INFO - PID 18904 | Cycle 1(2): loaded http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html)
04:12:23 INFO - PID 18904 | [talos glvideo result] Mean tick time across 100 ticks: 6.6058 ms
04:12:23 INFO - PID 18904 | Cycle 1(3): loaded http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html)
04:12:25 INFO - PID 18904 | [talos glvideo result] Mean tick time across 100 ticks: 6.62355 ms
04:12:25 INFO - PID 18904 | Cycle 1(4): loaded http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html)
04:12:26 INFO - PID 18904 | [talos glvideo result] Mean tick time across 100 ticks: 6.6175999999999995 ms
04:12:26 INFO - PID 18904 | Cycle 1(5): loaded http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53593/tests/webgl/benchmarks/video/video_upload.html)
04:12:26 INFO - PID 18904 | __start_tp_report
04:12:26 INFO - PID 18904 | _x_x_mozilla_page_load
04:12:26 INFO - PID 18904 | _x_x_mozilla_page_load_details
04:12:26 INFO - PID 18904 | |i|pagename|runs|
04:12:26 INFO - PID 18904 | |0;Mean tick time across 100 ticks: ;9.150549999999999;6.55875;6.6058;6.62355;6.6175999999999995
04:12:26 INFO - PID 18904 | __end_tp_report
04:12:26 INFO - PID 18904 | __start_cc_report
04:12:26 INFO - PID 18904 | _x_x_mozilla_cycle_collect,267
04:12:26 INFO - PID 18904 | __end_cc_report
04:12:26 INFO - PID 18904 | __startTimestamp1509970346705__endTimestamp
04:12:26 INFO - PID 18904 |
04:12:26 INFO - PID 18904 | ------- Summary: start -------
04:12:26 INFO - PID 18904 | Number of tests: 1
04:12:26 INFO - PID 18904 |
04:12:26 INFO - PID 18904 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:7.11 Median:6.62 stddev:1.14 (17.2%) stddev-sans-first:0.03
04:12:26 INFO - PID 18904 | Values: 9.2 6.6 6.6 6.6 6.6
04:12:26 INFO - PID 18904 | -------- Summary: end --------
04:12:26 INFO - PID 18904 |
04:12:27 INFO - PID 18904 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
04:12:27 INFO - TEST-INFO | 18904: exit 0
04:12:27 INFO - TEST-OK | glvideo | took 14181ms
04:12:27 INFO - TEST-START | displaylist_mutate
04:12:27 INFO - Initialising browser for displaylist_mutate test...
04:12:27 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53593/getInfo.html -profile /tmp/tmp_ZutEa/profile
04:12:27 INFO - TEST-INFO | started process 19131 (/builds/slave/test/build/application/firefox/firefox http://localhost:53593/getInfo.html)
04:12:32 INFO - TEST-INFO | 19131: exit 0
04:12:32 INFO - Browser initialized.
04:12:32 INFO - Running cycle 1/1 for displaylist_mutate test...
04:12:32 INFO - Using env: {'DISPLAY': ':0',
04:12:32 INFO - 'HOME': '/home/cltbld',
04:12:32 INFO - 'JSGC_DISABLE_POISONING': '1',
04:12:32 INFO - 'LANG': 'en_US.UTF-8',
04:12:32 INFO - 'LANGUAGE': 'en_US:en',
04:12:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
04:12:32 INFO - 'LOGNAME': 'cltbld',
04:12:32 INFO - 'MAIL': '/var/mail/cltbld',
04:12:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
04:12:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
04:12:32 INFO - 'MOZ_CRASHREPORTER': '1',
04:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
04:12:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
04:12:32 INFO - 'MOZ_NO_REMOTE': '1',
04:12:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
04:12:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
04:12:32 INFO - 'NO_EM_RESTART': '1',
04:12:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
04:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
04:12:32 INFO - 'PWD': '/builds/slave/test',
04:12:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
04:12:32 INFO - 'RUST_BACKTRACE': 'full',
04:12:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
04:12:32 INFO - 'SHELL': '/bin/bash',
04:12:32 INFO - 'SHLVL': '1',
04:12:32 INFO - 'TERM': 'linux',
04:12:32 INFO - 'TMOUT': '86400',
04:12:32 INFO - 'USER': 'cltbld',
04:12:32 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1509969692.853719-1173634263',
04:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
04:12:32 INFO - '_': '/tools/buildbot/bin/python'}
04:12:32 INFO - TEST-INFO | started process 19405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_ZutEa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
04:12:33 INFO - PID 19405 |
04:12:33 INFO - PID 19405 | (/builds/slave/test/build/application/firefox/firefox:19460): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
04:12:33 INFO - PID 19405 |
04:12:52 INFO - PID 19405 | Cycle 1(1): loaded http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html)
04:13:12 INFO - PID 19405 | Cycle 1(2): loaded http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html)
04:13:12 INFO - PID 19405 | Waiting for idle-callback
04:13:32 INFO - PID 19405 | Cycle 1(3): loaded http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html)
04:13:52 INFO - PID 19405 | Cycle 1(4): loaded http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html)
04:14:12 INFO - PID 19405 | Cycle 1(5): loaded http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53593/tests/layout/benchmarks/displaylist_mutate.html)
04:14:12 INFO - PID 19405 | Waiting for idle-callback
04:14:12 INFO - PID 19405 | __start_tp_report
04:14:12 INFO - PID 19405 | _x_x_mozilla_page_load
04:14:12 INFO - PID 19405 | _x_x_mozilla_page_load_details
04:14:12 INFO - PID 19405 | |i|pagename|runs|
04:14:12 INFO - PID 19405 | |0;/displaylist_mutate.html;18560.655;18978.329999999998;18865.415;18802.905;18779.84
04:14:12 INFO - PID 19405 | __end_tp_report
04:14:12 INFO - PID 19405 | __start_cc_report
04:14:12 INFO - PID 19405 | _x_x_mozilla_cycle_collect,207
04:14:12 INFO - PID 19405 | __end_cc_report
04:14:12 INFO - PID 19405 | __startTimestamp1509970452316__endTimestamp
04:14:12 INFO - PID 19405 |
04:14:12 INFO - PID 19405 | ------- Summary: start -------
04:14:12 INFO - PID 19405 | Number of tests: 1
04:14:12 INFO - PID 19405 |
04:14:12 INFO - PID 19405 | [#0] /displaylist_mutate.html Cycles:5 Average:18797.43 Median:18802.90 stddev:153.09 (0.8%) stddev-sans-first:88.83
04:14:12 INFO - PID 19405 | Values: 18560.7 18978.3 18865.4 18802.9 18779.8
04:14:12 INFO - PID 19405 | -------- Summary: end --------
04:14:12 INFO - PID 19405 |
04:14:12 INFO - TEST-INFO | 19405: exit 0
04:14:12 INFO - TEST-OK | displaylist_mutate | took 105594ms
04:14:12 INFO - SUITE-END | took 681s
04:14:13 INFO - Completed test suite (00:11:21)
04:14:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.591326313029519, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.686672287802136, 1.6764944134078212, 1.6808235294117646, 1.6856348314606744, 1.6923970671178792, 1.6840572390572393, 1.6798852183650617, 1.6809831932773112, 1.680019596864502, 1.681959080717489, 1.6895101351351351, 1.6812436974789915], "value": 1.6812436974789915, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6669999999999996, 1.6710818713450295, 1.6673291666666672, 1.6688740617180988, 1.6671541666666667, 1.6689741451209343, 1.6725627090301005, 1.6682235195996666, 1.6674874999999998, 1.6674916666666666, 1.6708055091819702, 1.6700626043405682], "value": 1.6688740617180988, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7225401836968997, 1.7396695652173915, 1.742758420441347, 1.7806231454005932, 1.7457417102966843, 1.7752189349112426, 1.7283899769585256, 1.7474839836924871, 1.7206249999999996, 1.7703038348082596, 1.7579847685998828, 1.731503173687248], "value": 1.7457417102966843, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7123244863013705, 1.7460994764397912, 1.7111976047904187, 1.6907100591715982, 1.7469737991266374, 1.7368750000000002, 1.749025349650349, 1.7316926406926412, 1.7453228621291443, 1.7261432269197587, 1.7185352233676976, 1.7522329246935202], "value": 1.7368750000000002, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.78808712121212, 4.172712100139083, 4.039037685060564, 4.006655540720962, 4.038425302826381, 4.144447513812155, 4.116502057613169, 3.8616988416988427, 4.178586350974928, 4.14421270718232, 4.202268907563026, 4.043780323450136], "value": 4.116502057613169, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.9146771037182027, 4.009679358717435, 3.937480314960633, 3.9688591269841274, 4.009779559118235, 4.108932238193017, 4.195188679245282, 4.1674374999999975, 4.12554639175258, 4.090071574642127, 4.167749999999997, 4.073727087576373], "value": 4.090071574642127, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.505093457943926, 3.546566193853426, 3.505110981308411, 3.484889663182346, 3.461447520184543, 3.514039812646372, 3.5633016627078398, 3.4569066820276513, 3.4535443037974694, 3.4414048165137623, 3.4288971428571426, 3.505549065420563], "value": 3.484889663182346, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.378902027027029, 3.4020663265306137, 3.413156996587032, 3.4916579406631785, 3.3965025466893053, 3.498304195804194, 3.508982456140349, 3.397173174872668, 3.372715008431704, 3.3446153846153877, 3.3621512605042008, 3.4611072664359805], "value": 3.4020663265306137, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.526151012891345, 5.682585227272725, 5.660754716981127, 5.715180952380948, 5.79241312741313, 5.68279356060606, 5.515229779411764, 5.781396917148366, 5.7480747126436835, 5.727690839694659, 5.715295238095236, 5.70565589353612], "value": 5.715180952380948, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.348275401069512, 5.618469101123593, 5.435448369565225, 5.618834269662922, 5.40674324324324, 5.4653415300546415, 5.183147668393791, 5.668852691218129, 5.495769230769229, 5.556486111111108, 5.60495798319328, 5.482301369863016], "value": 5.495769230769229, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.000233333333345, 10.172220338983053, 10.240682593856656, 10.754623655913969, 10.241416382252561, 10.102575757575758, 10.42010416666666, 10.7539605734767, 10.63914893617022, 9.869259868421045, 10.641382978723414, 10.276335616438352], "value": 10.276335616438352, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.310335051546382, 9.804705882352941, 9.853275862068948, 9.805049019607859, 10.309716494845356, 9.709174757281554, 9.953781094527377, 10.003449999999994, 10.587089947089943, 9.906163366336642, 9.95557213930348, 10.153071065989842], "value": 9.953781094527377, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.0728782287823, 10.949124087591246, 11.154814126394054, 10.952390510948911, 10.990915750915766, 11.032628676470575, 11.076254612546128, 11.154293680297402, 11.07101476014761, 10.949671532846713, 11.116888888888894, 11.196455223880603], "value": 11.07101476014761, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.527078947368404, 10.695855614973262, 10.53013157894736, 10.640744680851059, 10.586851851851856, 10.531763157894755, 10.584523809523802, 10.639494680851056, 10.641223404255296, 10.472356020942415, 10.642553191489377, 10.64194148936169], "value": 10.639494680851056, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.500666666666682, 12.659535864978924, 12.295143442622948, 12.659852320675114, 12.659683544303803, 12.246265306122446, 12.249040816326529, 12.149797570850202, 12.877660944205989, 12.452240663900406, 12.399648760330564, 12.767425531914899], "value": 12.452240663900406, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.97946107784431, 11.978293413173652, 12.049578313253031, 12.19862804878047, 11.836775147928956, 11.838047337278134, 11.90684523809523, 11.69704678362573, 12.122878787878797, 12.122454545454556, 11.905446428571416, 12.051927710843346], "value": 11.978293413173652, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.324660377358477, 11.409942965779477, 11.7652156862745, 11.454160305343532, 11.721855468749993, 11.450591603053436, 11.584266409266426, 11.45398854961832, 11.629496124031022, 11.584613899613903, 11.586833976833997, 11.197276119402995], "value": 11.584266409266426, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.177541899441374, 10.991291208791214, 10.990439560439572, 10.991895604395573, 10.992554945054927, 11.116416666666636, 10.813567567567539, 11.564971098265884, 11.113916666666652, 10.81440540540541, 10.992115384615358, 10.873559782608698], "value": 10.991895604395573, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.150549999999999, 6.55875, 6.6058, 6.62355, 6.6175999999999995], "value": 6.6117, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [18560.655, 18978.329999999998, 18865.415, 18802.905, 18779.84], "value": 18834.16, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
04:14:13 INFO - Return code: 0
04:14:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
04:14:13 INFO - # TBPL SUCCESS #
04:14:13 INFO - Running post-action listener: _package_coverage_data
04:14:13 INFO - Running post-action listener: _resource_record_post_action
04:14:13 INFO - [mozharness: 2017-11-06 12:14:13.233723Z] Finished run-tests step (success)
04:14:13 INFO - Running post-run listener: _resource_record_post_run
04:14:13 INFO - Total resource usage - Wall time: 701s; CPU: 35.0%; Read bytes: 8933376; Write bytes: 339480576; Read time: 920; Write time: 181772
04:14:13 INFO - TinderboxPrint: CPU usage
35.0%
04:14:13 INFO - TinderboxPrint: I/O read bytes / time
8,933,376 / 920
04:14:13 INFO - TinderboxPrint: I/O write bytes / time
339,480,576 / 181,772
04:14:13 INFO - TinderboxPrint: CPU idle
3,585.1 (65.1%)
04:14:13 INFO - TinderboxPrint: CPU system
319.5 (5.8%)
04:14:13 INFO - TinderboxPrint: CPU user
1,586.5 (28.8%)
04:14:13 INFO - TinderboxPrint: Swap in / out
0 / 0
04:14:13 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5824512; Read time: 0; Write time: 6688
04:14:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
04:14:13 INFO - run-tests - Wall time: 682s; CPU: 36.0%; Read bytes: 8933376; Write bytes: 332918784; Read time: 920; Write time: 175076
04:14:13 INFO - Running post-run listener: _upload_blobber_files
04:14:13 INFO - Blob upload gear active.
04:14:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
04:14:13 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
04:14:13 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']
04:14:13 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
04:14:13 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
04:14:13 INFO - SNIMissingWarning
04:14:13 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
04:14:13 INFO - InsecurePlatformWarning
04:14:13 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.)
04:14:13 INFO - SubjectAltNameWarning
04:14:14 INFO - (blobuploader) - INFO - Open directory for files ...
04:14:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
04:14:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:14:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:14:14 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
04:14:14 INFO - InsecurePlatformWarning
04:14:14 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.)
04:14:14 INFO - SubjectAltNameWarning
04:14:14 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
04:14:14 INFO - InsecurePlatformWarning
04:14:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
04:14:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:14:14 INFO - (blobuploader) - INFO - Done attempting.
04:14:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
04:14:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:14:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:14:14 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
04:14:14 INFO - InsecurePlatformWarning
04:14:15 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.)
04:14:15 INFO - SubjectAltNameWarning
04:14:19 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
04:14:19 INFO - InsecurePlatformWarning
04:14:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
04:14:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:14:20 INFO - (blobuploader) - INFO - Done attempting.
04:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
04:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:14:20 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
04:14:20 INFO - InsecurePlatformWarning
04:14:20 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.)
04:14:20 INFO - SubjectAltNameWarning
04:14:20 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
04:14:20 INFO - InsecurePlatformWarning
04:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
04:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:14:21 INFO - (blobuploader) - INFO - Done attempting.
04:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
04:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
04:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1.
04:14:21 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
04:14:21 INFO - InsecurePlatformWarning
04:14:21 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.)
04:14:21 INFO - SubjectAltNameWarning
04:14:21 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
04:14:21 INFO - InsecurePlatformWarning
04:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
04:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
04:14:21 INFO - (blobuploader) - INFO - Done attempting.
04:14:21 INFO - (blobuploader) - INFO - Iteration through files over.
04:14:21 INFO - Return code: 0
04:14:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
04:14:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
04:14:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d96f79b76f611bcc0d14b5d5fff3dca7d6d6b5d0c17f28b1497cc273302b82fd6d2515c440ddd13dd84abfd74fb7f2ee19bfafa1ff1a38d31c1906193b91aa0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c14682a1d4a2730fa127d361d85d8181f4c5780e2cc62c961fbb1b8ec7b079888c2c2291f2b4e1f5c3d7e3ac5cd79db7c5818abe55eacbc2187115f1266a3c6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77b813e09e66ee745965f9dec8149906e33807af1a685bba99787618f93540982cd9422214c1ef7673365c55d6bc3f2d9624ff024af532bf09c8f0278c6f2294", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de665db8cc7687202cba9495f02c910746139043c90d4d0e48b8b96ac14beb6bbc9ac070ea4c7dec6869de397d9137e19c8fe08ccc226db73273c76fe5bd6c00"}
04:14:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
04:14:21 INFO - Writing to file /builds/slave/test/properties/blobber_files
04:14:21 INFO - Contents:
04:14:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d96f79b76f611bcc0d14b5d5fff3dca7d6d6b5d0c17f28b1497cc273302b82fd6d2515c440ddd13dd84abfd74fb7f2ee19bfafa1ff1a38d31c1906193b91aa0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c14682a1d4a2730fa127d361d85d8181f4c5780e2cc62c961fbb1b8ec7b079888c2c2291f2b4e1f5c3d7e3ac5cd79db7c5818abe55eacbc2187115f1266a3c6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77b813e09e66ee745965f9dec8149906e33807af1a685bba99787618f93540982cd9422214c1ef7673365c55d6bc3f2d9624ff024af532bf09c8f0278c6f2294", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de665db8cc7687202cba9495f02c910746139043c90d4d0e48b8b96ac14beb6bbc9ac070ea4c7dec6869de397d9137e19c8fe08ccc226db73273c76fe5bd6c00"}
04:14:21 INFO - Running post-run listener: copy_logs_to_upload_dir
04:14:21 INFO - Copying logs to upload dir...
04:14:21 INFO - mkdir: /builds/slave/test/build/upload/logs
04:14:21 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=766.267171
========= master_lag: -0.02 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 46 secs) (at 2017-11-06 04:14:21.748416) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-06 04:14:21.752289) =========
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=745a4373a2a16df560f216e000000264-1509969692.853719-1173634263
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/4d96f79b76f611bcc0d14b5d5fff3dca7d6d6b5d0c17f28b1497cc273302b82fd6d2515c440ddd13dd84abfd74fb7f2ee19bfafa1ff1a38d31c1906193b91aa0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c14682a1d4a2730fa127d361d85d8181f4c5780e2cc62c961fbb1b8ec7b079888c2c2291f2b4e1f5c3d7e3ac5cd79db7c5818abe55eacbc2187115f1266a3c6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77b813e09e66ee745965f9dec8149906e33807af1a685bba99787618f93540982cd9422214c1ef7673365c55d6bc3f2d9624ff024af532bf09c8f0278c6f2294", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de665db8cc7687202cba9495f02c910746139043c90d4d0e48b8b96ac14beb6bbc9ac070ea4c7dec6869de397d9137e19c8fe08ccc226db73273c76fe5bd6c00"}
build_url:https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011218
build_url: 'https://queue.taskcluster.net/v1/task/L7hcqitnSHakEZCJN1QsWQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d96f79b76f611bcc0d14b5d5fff3dca7d6d6b5d0c17f28b1497cc273302b82fd6d2515c440ddd13dd84abfd74fb7f2ee19bfafa1ff1a38d31c1906193b91aa0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1c14682a1d4a2730fa127d361d85d8181f4c5780e2cc62c961fbb1b8ec7b079888c2c2291f2b4e1f5c3d7e3ac5cd79db7c5818abe55eacbc2187115f1266a3c6", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/77b813e09e66ee745965f9dec8149906e33807af1a685bba99787618f93540982cd9422214c1ef7673365c55d6bc3f2d9624ff024af532bf09c8f0278c6f2294", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de665db8cc7687202cba9495f02c910746139043c90d4d0e48b8b96ac14beb6bbc9ac070ea4c7dec6869de397d9137e19c8fe08ccc226db73273c76fe5bd6c00"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-06 04:14:21.782558) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 04:14:21.782885) =========
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=745a4373a2a16df560f216e000000264-1509969692.853719-1173634263
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004562
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-06 04:14:21.865378) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-06 04:14:21.865818) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-06 04:14:21.866202) =========
========= Total master_lag: 0.14 =========