builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-066
starttime: 1510743297.64
results: success (0)
revision: 45715ece25fcb064eee4f977ebd842d44a87f22b
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.636664) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.637484) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.637994) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.658465) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.659052) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-15 02:54:57-- 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% 227M=0s
2017-11-15 02:54:57 (227 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.094116
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.776645) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.777253) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.016364
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:57.827923) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-15 02:54:57.828404) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45715ece25fcb064eee4f977ebd842d44a87f22b --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 45715ece25fcb064eee4f977ebd842d44a87f22b --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=82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-15 02:54:57,881 truncating revision to first 12 chars
2017-11-15 02:54:57,881 Setting DEBUG logging.
2017-11-15 02:54:57,881 attempt 1/10
2017-11-15 02:54:57,881 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45715ece25fc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-15 02:54:58,809 unpacking tar archive at: mozilla-central-45715ece25fc/testing/mozharness/
program finished with exit code 0
elapsedTime=1.478433
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-15 02:54:59.327208) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:59.327854) =========
script_repo_revision: 45715ece25fcb064eee4f977ebd842d44a87f22b
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:59.328596) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:59.329092) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-15 02:54:59.345669) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 13 mins, 7 secs) (at 2017-11-15 02:54:59.346064) =========
/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=82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
02:54:59 INFO - MultiFileLogger online at 20171115 02:54:59 in /builds/slave/test
02:54:59 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
02:54:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
02:54:59 INFO - {'append_to_log': False,
02:54:59 INFO - 'base_work_dir': '/builds/slave/test',
02:54:59 INFO - 'blob_upload_branch': 'Firefox',
02:54:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
02:54:59 INFO - 'branch': 'Firefox',
02:54:59 INFO - 'buildbot_json_path': 'buildprops.json',
02:54:59 INFO - 'code_coverage': False,
02:54:59 INFO - 'config_files': ('talos/linux_config.py',),
02:54:59 INFO - 'default_actions': ('clobber',
02:54:59 INFO - 'read-buildbot-config',
02:54:59 INFO - 'download-and-extract',
02:54:59 INFO - 'populate-webroot',
02:54:59 INFO - 'create-virtualenv',
02:54:59 INFO - 'install',
02:54:59 INFO - 'setup-mitmproxy',
02:54:59 INFO - 'run-tests'),
02:54:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:54:59 INFO - 'disable_ccov_upload': False,
02:54:59 INFO - 'disable_stylo': False,
02:54:59 INFO - 'download_minidump_stackwalk': True,
02:54:59 INFO - 'download_symbols': 'ondemand',
02:54:59 INFO - 'e10s': False,
02:54:59 INFO - 'enable_stylo': False,
02:54:59 INFO - 'enable_webrender': False,
02:54:59 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
02:54:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:54:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:54:59 INFO - 'gecko_profile': False,
02:54:59 INFO - 'gecko_profile_interval': 0,
02:54:59 INFO - 'installer_path': 'installer.exe',
02:54:59 INFO - 'jsd_code_coverage': False,
02:54:59 INFO - 'log_level': 'info',
02:54:59 INFO - 'log_name': 'talos',
02:54:59 INFO - 'log_to_console': True,
02:54:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
02:54:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
02:54:59 INFO - 'opt_config_files': (),
02:54:59 INFO - 'pip_index': False,
02:54:59 INFO - 'suite': 'g4-e10s',
02:54:59 INFO - 'system_bits': '32',
02:54:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:54:59 INFO - 'title': 'talos-linux64-ix-066',
02:54:59 INFO - 'tooltool_cache': '/builds/tooltool_cache',
02:54:59 INFO - 'use_talos_json': True,
02:54:59 INFO - 'verify': 'False',
02:54:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
02:54:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:54:59 INFO - 'work_dir': 'build'}
02:54:59 INFO - [mozharness: 2017-11-15 10:54:59.533706Z] Running clobber step.
02:54:59 INFO - Running pre-action listener: _resource_record_pre_action
02:54:59 INFO - Running main action method: clobber
02:54:59 INFO - rmtree: /builds/slave/test/build
02:54:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
02:55:00 INFO - Running post-action listener: _resource_record_post_action
02:55:00 INFO - [mozharness: 2017-11-15 10:55:00.627034Z] Finished clobber step (success)
02:55:00 INFO - [mozharness: 2017-11-15 10:55:00.627132Z] Running read-buildbot-config step.
02:55:00 INFO - Running pre-action listener: _resource_record_pre_action
02:55:00 INFO - Running main action method: read_buildbot_config
02:55:00 INFO - Using buildbot properties:
02:55:00 INFO - {
02:55:00 INFO - "project": "",
02:55:00 INFO - "product": "firefox",
02:55:00 INFO - "who": "nerli@mozilla.com",
02:55:00 INFO - "installer_path": "public/build/target.tar.bz2",
02:55:00 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
02:55:00 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
02:55:00 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
02:55:00 INFO - "stage_platform": "linux64-qr",
02:55:00 INFO - "basedir": "/builds/slave/test",
02:55:00 INFO - "buildnumber": 196,
02:55:00 INFO - "platform": "ubuntu64_hw_qr",
02:55:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
02:55:00 INFO - "slavebuilddir": "test",
02:55:00 INFO - "taskId": "dEDqo95wTMquXsNeKYXovw",
02:55:00 INFO - "branch": "mozilla-central",
02:55:00 INFO - "script_repo_revision": "production",
02:55:00 INFO - "revision": "45715ece25fcb064eee4f977ebd842d44a87f22b",
02:55:00 INFO - "slavename": "talos-linux64-ix-066",
02:55:00 INFO - "repo_path": "mozilla-central"
02:55:00 INFO - }
02:55:00 INFO - Finding installer, test and symbols from parent task.
02:55:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEDqo95wTMquXsNeKYXovw'}, attempt #1
02:55:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dEDqo95wTMquXsNeKYXovw'}, attempt #1
02:55:01 INFO - Task dependencies: FG8YybqTSm6IxeVOoFGbcA
02:55:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA'}, attempt #1
02:55:02 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2
02:55:02 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.test_packages.json
02:55:02 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.crashreporter-symbols.zip
02:55:02 INFO - Running post-action listener: _resource_record_post_action
02:55:02 INFO - [mozharness: 2017-11-15 10:55:02.336287Z] Finished read-buildbot-config step (success)
02:55:02 INFO - [mozharness: 2017-11-15 10:55:02.336503Z] Running download-and-extract step.
02:55:02 INFO - Running pre-action listener: _resource_record_pre_action
02:55:02 INFO - Running main action method: download_and_extract
02:55:02 INFO - mkdir: /builds/slave/test/build/tests
02:55: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')]}
02:55:02 INFO - trying https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.test_packages.json
02:55:02 INFO - Downloading https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
02:55:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
02:55:02 INFO - Downloaded 989 bytes.
02:55:02 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
02:55:02 INFO - Using the following test package requirements:
02:55:02 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
02:55:02 INFO - u'common': [u'target.common.tests.zip'],
02:55:02 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
02:55:02 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
02:55:02 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
02:55:02 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
02:55:02 INFO - u'mozbase': [u'target.common.tests.zip'],
02:55:02 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
02:55:02 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
02:55:02 INFO - u'web-platform': [u'target.common.tests.zip',
02:55:02 INFO - u'target.web-platform.tests.tar.gz'],
02:55:02 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
02:55:02 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
02:55:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.common.tests.zip
02:55:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.common.tests.zip'}, attempt #1
02:55:02 INFO - Fetch https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.common.tests.zip into memory
02:55:03 INFO - Content-Length response header: 57895372
02:55:03 INFO - Bytes received: 57895372
02:55:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.talos.tests.zip
02:55:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
02:55:15 INFO - Fetch https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.talos.tests.zip into memory
02:55:15 INFO - Content-Length response header: 18771603
02:55:15 INFO - Bytes received: 18771603
02:55:16 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')]}
02:55:16 INFO - trying https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2
02:55:16 INFO - Downloading https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
02:55:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
02:55:17 INFO - Downloaded 62287197 bytes.
02:55:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2
02:55:17 INFO - mkdir: /builds/slave/test/properties
02:55:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
02:55:17 INFO - Writing to file /builds/slave/test/properties/build_url
02:55:17 INFO - Contents:
02:55:17 INFO - build_url:https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2
02:55:17 INFO - Running post-action listener: _resource_record_post_action
02:55:17 INFO - Running post-action listener: find_tests_for_verification
02:55:17 INFO - Running post-action listener: set_extra_try_arguments
02:55:17 INFO - [mozharness: 2017-11-15 10:55:17.451161Z] Finished download-and-extract step (success)
02:55:17 INFO - [mozharness: 2017-11-15 10:55:17.451373Z] Running populate-webroot step.
02:55:17 INFO - Running pre-action listener: _resource_record_pre_action
02:55:17 INFO - Running main action method: populate_webroot
02:55:17 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
02:55:17 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['tresize',
02:55:17 INFO - 'tcanvasmark']},
02:55:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
02:55:17 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['dromaeo_css',
02:55:17 INFO - 'kraken']},
02:55:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:55:17 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:55:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'tests': ['damp', 'tps']},
02:55:17 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['damp', 'tps']},
02:55:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
02:55:17 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['dromaeo_dom']},
02:55:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
02:55:17 INFO - 'glvideo',
02:55:17 INFO - 'displaylist_mutate']},
02:55:17 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['basic_compositor_video',
02:55:17 INFO - 'glvideo']},
02:55:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
02:55:17 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['ts_paint_webext',
02:55:17 INFO - 'tp5o_webext']},
02:55:17 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
02:55:17 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:55:17 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:55:17 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:55:17 INFO - 'talos_options': ['--mitmproxy',
02:55:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:55:17 INFO - '--firstNonBlankPaint'],
02:55:17 INFO - 'tests': ['tp6_google_heavy',
02:55:17 INFO - 'tp6_youtube_heavy',
02:55:17 INFO - 'tp6_amazon_heavy',
02:55:17 INFO - 'tp6_facebook_heavy']},
02:55:17 INFO - 'other-e10s': {'tests': ['a11yr',
02:55:17 INFO - 'ts_paint',
02:55:17 INFO - 'tpaint',
02:55:17 INFO - 'sessionrestore',
02:55:17 INFO - 'sessionrestore_many_windows',
02:55:17 INFO - 'sessionrestore_no_auto_restore',
02:55:17 INFO - 'tabpaint',
02:55:17 INFO - 'cpstartup']},
02:55:17 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['a11yr',
02:55:17 INFO - 'ts_paint',
02:55:17 INFO - 'tpaint',
02:55:17 INFO - 'sessionrestore',
02:55:17 INFO - 'sessionrestore_many_windows',
02:55:17 INFO - 'sessionrestore_no_auto_restore',
02:55:17 INFO - 'tabpaint',
02:55:17 INFO - 'cpstartup']},
02:55:17 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
02:55:17 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
02:55:17 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['perf_reftest_singletons']},
02:55:17 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['perf_reftest']},
02:55:17 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
02:55:17 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['speedometer']},
02:55:17 INFO - 'svgr-e10s': {'tests': ['tsvgx',
02:55:17 INFO - 'tsvgr_opacity',
02:55:17 INFO - 'tart',
02:55:17 INFO - 'tscrollx',
02:55:17 INFO - 'tsvg_static']},
02:55:17 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['tsvgx',
02:55:17 INFO - 'tsvgr_opacity',
02:55:17 INFO - 'tart',
02:55:17 INFO - 'tscrollx',
02:55:17 INFO - 'tsvg_static']},
02:55:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
02:55:17 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'talos_options': ['--disable-stylo'],
02:55:17 INFO - 'tests': ['tp5o']},
02:55:17 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:55:17 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:55:17 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:55:17 INFO - 'talos_options': ['--mitmproxy',
02:55:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:55:17 INFO - '--firstNonBlankPaint'],
02:55:17 INFO - 'tests': ['tp6_google',
02:55:17 INFO - 'tp6_youtube',
02:55:17 INFO - 'tp6_amazon',
02:55:17 INFO - 'tp6_facebook']},
02:55:17 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:55:17 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:55:17 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:55:17 INFO - 'talos_options': ['--disable-stylo',
02:55:17 INFO - '--mitmproxy',
02:55:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:55:17 INFO - '--firstNonBlankPaint'],
02:55:17 INFO - 'tests': ['tp6_google',
02:55:17 INFO - 'tp6_youtube',
02:55:17 INFO - 'tp6_amazon',
02:55:17 INFO - 'tp6_facebook']},
02:55:17 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:55:17 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:55:17 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:55:17 INFO - 'talos_options': ['--stylo-threads=1',
02:55:17 INFO - '--mitmproxy',
02:55:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:55:17 INFO - '--firstNonBlankPaint'],
02:55:17 INFO - 'tests': ['tp6_google',
02:55:17 INFO - 'tp6_youtube',
02:55:17 INFO - 'tp6_amazon',
02:55:17 INFO - 'tp6_facebook']},
02:55:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'talos_options': ['--xperf_path',
02:55:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:55:17 INFO - 'tests': ['tp5n']},
02:55:17 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:55:17 INFO - 'talos_options': ['--disable-stylo',
02:55:17 INFO - '--xperf_path',
02:55:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:55:17 INFO - 'tests': ['tp5n']}}}
02:55:17 INFO - Running post-action listener: _resource_record_post_action
02:55:17 INFO - [mozharness: 2017-11-15 10:55:17.471040Z] Finished populate-webroot step (success)
02:55:17 INFO - [mozharness: 2017-11-15 10:55:17.471151Z] Running create-virtualenv step.
02:55:17 INFO - Running pre-action listener: _resource_record_pre_action
02:55:17 INFO - Running main action method: create_virtualenv
02:55:17 INFO - Creating virtualenv /builds/slave/test/build/venv
02:55:17 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
02:55:17 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
02:55:17 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
02:55:17 INFO - Using real prefix '/usr'
02:55:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python
02:55:17 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
02:55:20 INFO - Installing setuptools, pip, wheel...done.
02:55:20 INFO - Return code: 0
02:55:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:20 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:20 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
02:55:20 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
02:55:20 INFO - Using env: {'DISPLAY': ':0',
02:55:20 INFO - 'HOME': '/home/cltbld',
02:55:20 INFO - 'LANG': 'en_US.UTF-8',
02:55:20 INFO - 'LANGUAGE': 'en_US:en',
02:55:20 INFO - 'LOGNAME': 'cltbld',
02:55:20 INFO - 'MAIL': '/var/mail/cltbld',
02:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:20 INFO - 'MOZ_NO_REMOTE': '1',
02:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:20 INFO - 'NO_EM_RESTART': '1',
02:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:20 INFO - 'PWD': '/builds/slave/test',
02:55:20 INFO - 'SHELL': '/bin/bash',
02:55:20 INFO - 'SHLVL': '1',
02:55:20 INFO - 'TERM': 'linux',
02:55:20 INFO - 'TMOUT': '86400',
02:55:20 INFO - 'USER': 'cltbld',
02:55:20 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:20 INFO - '_': '/tools/buildbot/bin/python'}
02:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
02:55:20 INFO - Return code: 0
02:55:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:20 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:20 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
02:55:20 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
02:55:20 INFO - Using env: {'DISPLAY': ':0',
02:55:20 INFO - 'HOME': '/home/cltbld',
02:55:20 INFO - 'LANG': 'en_US.UTF-8',
02:55:20 INFO - 'LANGUAGE': 'en_US:en',
02:55:20 INFO - 'LOGNAME': 'cltbld',
02:55:20 INFO - 'MAIL': '/var/mail/cltbld',
02:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:20 INFO - 'MOZ_NO_REMOTE': '1',
02:55:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:20 INFO - 'NO_EM_RESTART': '1',
02:55:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:20 INFO - 'PWD': '/builds/slave/test',
02:55:20 INFO - 'SHELL': '/bin/bash',
02:55:20 INFO - 'SHLVL': '1',
02:55:20 INFO - 'TERM': 'linux',
02:55:20 INFO - 'TMOUT': '86400',
02:55:20 INFO - 'USER': 'cltbld',
02:55:20 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:20 INFO - '_': '/tools/buildbot/bin/python'}
02:55:21 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:21 INFO - Collecting psutil>=3.1.1
02:55:22 INFO - Installing collected packages: psutil
02:55:22 INFO - Successfully installed psutil-3.1.1
02:55:22 INFO - Return code: 0
02:55:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
02:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:22 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:22 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
02:55:22 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
02:55:22 INFO - Using env: {'DISPLAY': ':0',
02:55:22 INFO - 'HOME': '/home/cltbld',
02:55:22 INFO - 'LANG': 'en_US.UTF-8',
02:55:22 INFO - 'LANGUAGE': 'en_US:en',
02:55:22 INFO - 'LOGNAME': 'cltbld',
02:55:22 INFO - 'MAIL': '/var/mail/cltbld',
02:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:22 INFO - 'MOZ_NO_REMOTE': '1',
02:55:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:22 INFO - 'NO_EM_RESTART': '1',
02:55:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:22 INFO - 'PWD': '/builds/slave/test',
02:55:22 INFO - 'SHELL': '/bin/bash',
02:55:22 INFO - 'SHLVL': '1',
02:55:22 INFO - 'TERM': 'linux',
02:55:22 INFO - 'TMOUT': '86400',
02:55:22 INFO - 'USER': 'cltbld',
02:55:22 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:22 INFO - '_': '/tools/buildbot/bin/python'}
02:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:22 INFO - Collecting mozsystemmonitor==0.3
02:55:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
02:55:23 INFO - Installing collected packages: mozsystemmonitor
02:55:24 INFO - Successfully installed mozsystemmonitor-0.3
02:55:24 INFO - Return code: 0
02:55:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
02:55: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')]}
02:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55: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')]}
02:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:24 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:24 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
02:55:24 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
02:55:24 INFO - Using env: {'DISPLAY': ':0',
02:55:24 INFO - 'HOME': '/home/cltbld',
02:55:24 INFO - 'LANG': 'en_US.UTF-8',
02:55:24 INFO - 'LANGUAGE': 'en_US:en',
02:55:24 INFO - 'LOGNAME': 'cltbld',
02:55:24 INFO - 'MAIL': '/var/mail/cltbld',
02:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:24 INFO - 'MOZ_NO_REMOTE': '1',
02:55:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:24 INFO - 'NO_EM_RESTART': '1',
02:55:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:24 INFO - 'PWD': '/builds/slave/test',
02:55:24 INFO - 'SHELL': '/bin/bash',
02:55:24 INFO - 'SHLVL': '1',
02:55:24 INFO - 'TERM': 'linux',
02:55:24 INFO - 'TMOUT': '86400',
02:55:24 INFO - 'USER': 'cltbld',
02:55:24 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:24 INFO - '_': '/tools/buildbot/bin/python'}
02:55:25 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:25 INFO - Collecting jsonschema==2.5.1
02:55:26 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
02:55:26 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
02:55:27 INFO - Installing collected packages: functools32, jsonschema
02:55:27 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
02:55:27 INFO - Return code: 0
02:55:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
02:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:27 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:27 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
02:55:27 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
02:55:27 INFO - Using env: {'DISPLAY': ':0',
02:55:27 INFO - 'HOME': '/home/cltbld',
02:55:27 INFO - 'LANG': 'en_US.UTF-8',
02:55:27 INFO - 'LANGUAGE': 'en_US:en',
02:55:27 INFO - 'LOGNAME': 'cltbld',
02:55:27 INFO - 'MAIL': '/var/mail/cltbld',
02:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:27 INFO - 'MOZ_NO_REMOTE': '1',
02:55:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:27 INFO - 'NO_EM_RESTART': '1',
02:55:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:27 INFO - 'PWD': '/builds/slave/test',
02:55:27 INFO - 'SHELL': '/bin/bash',
02:55:27 INFO - 'SHLVL': '1',
02:55:27 INFO - 'TERM': 'linux',
02:55:27 INFO - 'TMOUT': '86400',
02:55:27 INFO - 'USER': 'cltbld',
02:55:27 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:27 INFO - '_': '/tools/buildbot/bin/python'}
02:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
02:55:27 INFO - Return code: 0
02:55:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
02:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:27 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:27 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
02:55:27 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
02:55:27 INFO - Using env: {'DISPLAY': ':0',
02:55:27 INFO - 'HOME': '/home/cltbld',
02:55:27 INFO - 'LANG': 'en_US.UTF-8',
02:55:27 INFO - 'LANGUAGE': 'en_US:en',
02:55:27 INFO - 'LOGNAME': 'cltbld',
02:55:27 INFO - 'MAIL': '/var/mail/cltbld',
02:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:27 INFO - 'MOZ_NO_REMOTE': '1',
02:55:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:27 INFO - 'NO_EM_RESTART': '1',
02:55:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:27 INFO - 'PWD': '/builds/slave/test',
02:55:27 INFO - 'SHELL': '/bin/bash',
02:55:27 INFO - 'SHLVL': '1',
02:55:27 INFO - 'TERM': 'linux',
02:55:27 INFO - 'TMOUT': '86400',
02:55:27 INFO - 'USER': 'cltbld',
02:55:27 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:27 INFO - '_': '/tools/buildbot/bin/python'}
02:55:28 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:28 INFO - Collecting blobuploader==1.2.4
02:55:29 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
02:55:30 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
02:55:31 INFO - Installing collected packages: requests, docopt, blobuploader
02:55:31 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
02:55:31 INFO - Return code: 0
02:55:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:55: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')]}
02:55:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55: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')]}
02:55:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:31 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:31 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
02:55:31 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
02:55:31 INFO - Using env: {'DISPLAY': ':0',
02:55:31 INFO - 'HOME': '/home/cltbld',
02:55:31 INFO - 'LANG': 'en_US.UTF-8',
02:55:31 INFO - 'LANGUAGE': 'en_US:en',
02:55:31 INFO - 'LOGNAME': 'cltbld',
02:55:31 INFO - 'MAIL': '/var/mail/cltbld',
02:55:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:31 INFO - 'MOZ_NO_REMOTE': '1',
02:55:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:31 INFO - 'NO_EM_RESTART': '1',
02:55:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:31 INFO - 'PWD': '/builds/slave/test',
02:55:31 INFO - 'SHELL': '/bin/bash',
02:55:31 INFO - 'SHLVL': '1',
02:55:31 INFO - 'TERM': 'linux',
02:55:31 INFO - 'TMOUT': '86400',
02:55:31 INFO - 'USER': 'cltbld',
02:55:31 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:31 INFO - '_': '/tools/buildbot/bin/python'}
02:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:32 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:55:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:55:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:55:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:55:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:55:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:55:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:55:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:55:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:55:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:55:34 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:55:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:55:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:55:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:55:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:55:35 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:55:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:55:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:55:35 INFO - Running setup.py install for manifestparser: started
02:55:35 INFO - Running setup.py install for manifestparser: finished with status 'done'
02:55:35 INFO - Running setup.py install for mozcrash: started
02:55:35 INFO - Running setup.py install for mozcrash: finished with status 'done'
02:55:35 INFO - Running setup.py install for mozdebug: started
02:55:35 INFO - Running setup.py install for mozdebug: finished with status 'done'
02:55:35 INFO - Running setup.py install for mozdevice: started
02:55:36 INFO - Running setup.py install for mozdevice: finished with status 'done'
02:55:36 INFO - Running setup.py install for mozfile: started
02:55:36 INFO - Running setup.py install for mozfile: finished with status 'done'
02:55:36 INFO - Running setup.py install for mozhttpd: started
02:55:36 INFO - Running setup.py install for mozhttpd: finished with status 'done'
02:55:36 INFO - Running setup.py install for mozinfo: started
02:55:36 INFO - Running setup.py install for mozinfo: finished with status 'done'
02:55:36 INFO - Running setup.py install for mozInstall: started
02:55:37 INFO - Running setup.py install for mozInstall: finished with status 'done'
02:55:37 INFO - Running setup.py install for mozleak: started
02:55:37 INFO - Running setup.py install for mozleak: finished with status 'done'
02:55:37 INFO - Running setup.py install for mozlog: started
02:55:37 INFO - Running setup.py install for mozlog: finished with status 'done'
02:55:37 INFO - Running setup.py install for moznetwork: started
02:55:37 INFO - Running setup.py install for moznetwork: finished with status 'done'
02:55:37 INFO - Running setup.py install for mozprocess: started
02:55:37 INFO - Running setup.py install for mozprocess: finished with status 'done'
02:55:37 INFO - Running setup.py install for mozprofile: started
02:55:38 INFO - Running setup.py install for mozprofile: finished with status 'done'
02:55:38 INFO - Running setup.py install for mozrunner: started
02:55:38 INFO - Running setup.py install for mozrunner: finished with status 'done'
02:55:38 INFO - Running setup.py install for mozscreenshot: started
02:55:38 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
02:55:38 INFO - Running setup.py install for moztest: started
02:55:38 INFO - Running setup.py install for moztest: finished with status 'done'
02:55:38 INFO - Running setup.py install for mozversion: started
02:55:39 INFO - Running setup.py install for mozversion: finished with status 'done'
02:55:39 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
02:55:39 INFO - Return code: 0
02:55:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:39 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:39 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
02:55:39 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
02:55:39 INFO - Using env: {'DISPLAY': ':0',
02:55:39 INFO - 'HOME': '/home/cltbld',
02:55:39 INFO - 'LANG': 'en_US.UTF-8',
02:55:39 INFO - 'LANGUAGE': 'en_US:en',
02:55:39 INFO - 'LOGNAME': 'cltbld',
02:55:39 INFO - 'MAIL': '/var/mail/cltbld',
02:55:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:39 INFO - 'MOZ_NO_REMOTE': '1',
02:55:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:39 INFO - 'NO_EM_RESTART': '1',
02:55:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:39 INFO - 'PWD': '/builds/slave/test',
02:55:39 INFO - 'SHELL': '/bin/bash',
02:55:39 INFO - 'SHLVL': '1',
02:55:39 INFO - 'TERM': 'linux',
02:55:39 INFO - 'TMOUT': '86400',
02:55:39 INFO - 'USER': 'cltbld',
02:55:39 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:39 INFO - '_': '/tools/buildbot/bin/python'}
02:55:39 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:39 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:55:40 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))
02:55:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:55:40 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))
02:55:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:55:40 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))
02:55:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:55:40 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))
02:55:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:55:40 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))
02:55:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:55:41 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))
02:55:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:55:41 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))
02:55:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:55:41 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))
02:55:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:55:41 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))
02:55:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:55:41 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))
02:55:41 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:55:42 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))
02:55:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:55:42 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))
02:55:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:55:42 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))
02:55:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:55:42 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))
02:55:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:55:42 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))
02:55:42 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:55:43 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))
02:55:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:55:43 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))
02:55:43 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:55:44 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
02:55:44 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))
02:55:44 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))
02:55:44 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))
02:55:44 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))
02:55:44 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))
02:55:44 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))
02:55:44 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:55:45 INFO - Installing collected packages: six, blessings
02:55:45 INFO - Successfully installed blessings-1.6 six-1.10.0
02:55:45 INFO - Return code: 0
02:55:45 INFO - Done creating virtualenv /builds/slave/test/build/venv.
02:55:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:55:46 INFO - Reading from file tmpfile_stdout
02:55:46 INFO - Reading from file tmpfile_stderr
02:55:46 INFO - Current package versions:
02:55:46 INFO - blessings == 1.6
02:55:46 INFO - blobuploader == 1.2.4
02:55:46 INFO - docopt == 0.6.1
02:55:46 INFO - functools32 == 3.2.3.post2
02:55:46 INFO - jsonschema == 2.5.1
02:55:46 INFO - manifestparser == 1.2
02:55:46 INFO - mozInstall == 1.14
02:55:46 INFO - mozcrash == 1.0
02:55:46 INFO - mozdebug == 0.1
02:55:46 INFO - mozdevice == 0.51
02:55:46 INFO - mozfile == 1.2
02:55:46 INFO - mozhttpd == 0.7
02:55:46 INFO - mozinfo == 0.10
02:55:46 INFO - mozleak == 0.1
02:55:46 INFO - mozlog == 3.5
02:55:46 INFO - moznetwork == 0.27
02:55:46 INFO - mozprocess == 0.25
02:55:46 INFO - mozprofile == 0.29
02:55:46 INFO - mozrunner == 6.13
02:55:46 INFO - mozscreenshot == 0.1
02:55:46 INFO - mozsystemmonitor == 0.3
02:55:46 INFO - moztest == 0.8
02:55:46 INFO - mozversion == 1.4
02:55:46 INFO - psutil == 3.1.1
02:55:46 INFO - requests == 1.2.3
02:55:46 INFO - six == 1.10.0
02:55:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:46 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:46 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
02:55:46 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
02:55:46 INFO - Using env: {'DISPLAY': ':0',
02:55:46 INFO - 'HOME': '/home/cltbld',
02:55:46 INFO - 'LANG': 'en_US.UTF-8',
02:55:46 INFO - 'LANGUAGE': 'en_US:en',
02:55:46 INFO - 'LOGNAME': 'cltbld',
02:55:46 INFO - 'MAIL': '/var/mail/cltbld',
02:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:46 INFO - 'MOZ_NO_REMOTE': '1',
02:55:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:46 INFO - 'NO_EM_RESTART': '1',
02:55:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:46 INFO - 'PWD': '/builds/slave/test',
02:55:46 INFO - 'SHELL': '/bin/bash',
02:55:46 INFO - 'SHLVL': '1',
02:55:46 INFO - 'TERM': 'linux',
02:55:46 INFO - 'TMOUT': '86400',
02:55:46 INFO - 'USER': 'cltbld',
02:55:46 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:46 INFO - '_': '/tools/buildbot/bin/python'}
02:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:46 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))
02:55:46 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))
02:55:46 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))
02:55:46 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))
02:55:46 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))
02:55:46 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))
02:55:46 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))
02:55:46 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))
02:55:46 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))
02:55:46 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
02:55:47 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:55:48 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
02:55:48 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))
02:55:48 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))
02:55:48 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))
02:55:48 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))
02:55:49 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
02:55:49 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:55:50 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
02:55:50 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:55:51 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
02:55:51 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:55:52 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
02:55:52 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
02:55:53 INFO - Found existing installation: requests 1.2.3
02:55:53 INFO - Uninstalling requests-1.2.3:
02:55:53 INFO - Successfully uninstalled requests-1.2.3
02:55:53 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
02:55:53 INFO - Return code: 0
02:55:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
02:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:55:53 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 0x22512d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25cad70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, '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': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729', '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
02:55:53 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
02:55:53 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
02:55:53 INFO - Using env: {'DISPLAY': ':0',
02:55:53 INFO - 'HOME': '/home/cltbld',
02:55:53 INFO - 'LANG': 'en_US.UTF-8',
02:55:53 INFO - 'LANGUAGE': 'en_US:en',
02:55:53 INFO - 'LOGNAME': 'cltbld',
02:55:53 INFO - 'MAIL': '/var/mail/cltbld',
02:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:53 INFO - 'MOZ_NO_REMOTE': '1',
02:55:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:53 INFO - 'NO_EM_RESTART': '1',
02:55:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:53 INFO - 'PWD': '/builds/slave/test',
02:55:53 INFO - 'SHELL': '/bin/bash',
02:55:53 INFO - 'SHLVL': '1',
02:55:53 INFO - 'TERM': 'linux',
02:55:53 INFO - 'TMOUT': '86400',
02:55:53 INFO - 'USER': 'cltbld',
02:55:53 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:53 INFO - '_': '/tools/buildbot/bin/python'}
02:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple
02:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
02:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
02:55:53 INFO - Return code: 0
02:55:53 INFO - Running post-action listener: _resource_record_post_action
02:55:53 INFO - Running post-action listener: _start_resource_monitoring
02:55:53 INFO - Starting resource monitoring.
02:55:53 INFO - [mozharness: 2017-11-15 10:55:53.986174Z] Finished create-virtualenv step (success)
02:55:53 INFO - [mozharness: 2017-11-15 10:55:53.986688Z] Running install step.
02:55:53 INFO - Running pre-action listener: _resource_record_pre_action
02:55:53 INFO - Running main action method: install
02:55:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:55:54 INFO - Reading from file tmpfile_stdout
02:55:54 INFO - Reading from file tmpfile_stderr
02:55:54 INFO - Detecting whether we're running mozinstall >=1.0...
02:55:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
02:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
02:55:54 INFO - Reading from file tmpfile_stdout
02:55:54 INFO - Output received:
02:55:54 INFO - Usage: mozinstall [options] installer
02:55:54 INFO - Options:
02:55:54 INFO - -h, --help show this help message and exit
02:55:54 INFO - -d DEST, --destination=DEST
02:55:54 INFO - Directory to install application into. [default:
02:55:54 INFO - "/builds/slave/test"]
02:55:54 INFO - --app=APP Application being installed. [default: firefox]
02:55:54 INFO - mkdir: /builds/slave/test/build/application
02:55:54 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']
02:55:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
02:56:12 INFO - Reading from file tmpfile_stdout
02:56:12 INFO - Output received:
02:56:12 INFO - /builds/slave/test/build/application/firefox/firefox
02:56:12 INFO - Running post-action listener: _resource_record_post_action
02:56:12 INFO - [mozharness: 2017-11-15 10:56:12.927826Z] Finished install step (success)
02:56:12 INFO - [mozharness: 2017-11-15 10:56:12.928069Z] Running setup-mitmproxy step.
02:56:12 INFO - Running pre-action listener: _resource_record_pre_action
02:56:12 INFO - Running main action method: setup_mitmproxy
02:56:12 INFO - Skipping: mitmproxy is not required
02:56:12 INFO - Running post-action listener: _resource_record_post_action
02:56:12 INFO - [mozharness: 2017-11-15 10:56:12.929175Z] Finished setup-mitmproxy step (success)
02:56:12 INFO - [mozharness: 2017-11-15 10:56:12.929390Z] Running run-tests step.
02:56:12 INFO - Running pre-action listener: _resource_record_pre_action
02:56:12 INFO - Running pre-action listener: _set_gcov_prefix
02:56:12 INFO - Running main action method: run_tests
02:56:12 WARNING - Try message not found.
02:56:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
02:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
02:56:12 INFO - Python 2.7.3
02:56:12 INFO - Return code: 0
02:56:12 INFO - grabbing minidump binary from tooltool
02:56:12 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')]}
02:56:12 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 0x25ce070>, '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 0x25cf080>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25c92a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
02:56:12 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
02:56:12 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
02:56:12 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
02:56:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
02:56:13 INFO - Return code: 0
02:56:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
02:56:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
02:56:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
02:56:13 INFO - ENV: RUST_BACKTRACE is now full
02:56:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
02:56:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
02:56:13 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/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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
02:56:13 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/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-066 --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
02:56:13 INFO - Using env: {'DISPLAY': ':0',
02:56:13 INFO - 'HOME': '/home/cltbld',
02:56:13 INFO - 'LANG': 'en_US.UTF-8',
02:56:13 INFO - 'LANGUAGE': 'en_US:en',
02:56:13 INFO - 'LOGNAME': 'cltbld',
02:56:13 INFO - 'MAIL': '/var/mail/cltbld',
02:56:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:56:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:56:13 INFO - 'MOZ_ACCELERATED': '1',
02:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:56:13 INFO - 'MOZ_NO_REMOTE': '1',
02:56:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:56:13 INFO - 'MOZ_WEBRENDER': '1',
02:56:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:56:13 INFO - 'NO_EM_RESTART': '1',
02:56:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:56:13 INFO - 'PWD': '/builds/slave/test',
02:56:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:56:13 INFO - 'RUST_BACKTRACE': 'full',
02:56:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:56:13 INFO - 'SHELL': '/bin/bash',
02:56:13 INFO - 'SHLVL': '1',
02:56:13 INFO - 'TERM': 'linux',
02:56:13 INFO - 'TMOUT': '86400',
02:56:13 INFO - 'USER': 'cltbld',
02:56:13 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:56:13 INFO - '_': '/tools/buildbot/bin/python'}
02:56:13 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/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-066', '--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
02:56:13 INFO - mozversion application_buildid: 20171115095750
02:56:13 INFO - mozversion application_changeset: 45715ece25fcb064eee4f977ebd842d44a87f22b
02:56:13 INFO - mozversion application_display_name: Nightly
02:56:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:56:13 INFO - mozversion application_name: Firefox
02:56:13 INFO - mozversion application_remotingname: firefox
02:56:13 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
02:56:13 INFO - mozversion application_vendor: Mozilla
02:56:13 INFO - mozversion application_version: 59.0a1
02:56:13 INFO - mozversion platform_buildid: 20171115095750
02:56:13 INFO - mozversion platform_changeset: 45715ece25fcb064eee4f977ebd842d44a87f22b
02:56:13 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
02:56:13 INFO - mozversion platform_version: 59.0a1
02:56:13 INFO - using testdate: 1510743373
02:56:13 INFO - actual date: 1510743373
02:56:13 INFO - starting webserver on 'localhost:53037'
02:56:13 INFO - SUITE-START | Running 3 tests
02:56:13 INFO - TEST-START | basic_compositor_video
02:56:13 INFO - Initialising browser for basic_compositor_video test...
02:56:13 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53037/getInfo.html -profile /tmp/tmpBVim6l/profile
02:56:13 INFO - TEST-INFO | started process 3582 (/builds/slave/test/build/application/firefox/firefox http://localhost:53037/getInfo.html)
02:56:20 INFO - TEST-INFO | 3582: exit 0
02:56:20 INFO - Browser initialized.
02:56:20 INFO - Running cycle 1/1 for basic_compositor_video test...
02:56:20 INFO - Using env: {'DISPLAY': ':0',
02:56:20 INFO - 'HOME': '/home/cltbld',
02:56:20 INFO - 'JSGC_DISABLE_POISONING': '1',
02:56:20 INFO - 'LANG': 'en_US.UTF-8',
02:56:20 INFO - 'LANGUAGE': 'en_US:en',
02:56:20 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:56:20 INFO - 'LOGNAME': 'cltbld',
02:56:20 INFO - 'MAIL': '/var/mail/cltbld',
02:56:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:56:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:56:20 INFO - 'MOZ_ACCELERATED': '1',
02:56:20 INFO - 'MOZ_CRASHREPORTER': '1',
02:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:56:20 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:56:20 INFO - 'MOZ_NO_REMOTE': '1',
02:56:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:56:20 INFO - 'MOZ_WEBRENDER': '1',
02:56:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:56:20 INFO - 'NO_EM_RESTART': '1',
02:56:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:56:20 INFO - 'PWD': '/builds/slave/test',
02:56:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:56:20 INFO - 'RUST_BACKTRACE': 'full',
02:56:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:56:20 INFO - 'SHELL': '/bin/bash',
02:56:20 INFO - 'SHLVL': '1',
02:56:20 INFO - 'TERM': 'linux',
02:56:20 INFO - 'TMOUT': '86400',
02:56:20 INFO - 'USER': 'cltbld',
02:56:20 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
02:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:56:20 INFO - '_': '/tools/buildbot/bin/python'}
02:56:20 INFO - TEST-INFO | started process 3823 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBVim6l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
02:56:21 INFO - PID 3823 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:56:21 INFO - PID 3823 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:56:21 INFO - PID 3823 |
02:56:21 INFO - PID 3823 | (/builds/slave/test/build/application/firefox/firefox:3884): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:56:21 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.8120337394564205 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.605286458333333 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.664755772646536 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.657177954847277 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.247364718614717 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2270645161290332 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.262251420454546 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.266311300639662 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.3991715542522005 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.4260730088495555 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.855671521035599 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.821240963855418 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.407567901234556 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.093421985815607 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.301496350364966 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.170286738351236 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.371547911547914 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.169157706093189 ms/frame
02:57:07 INFO - PID 3823 |
02:57:07 INFO - PID 3823 | Cycle 1(1): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
02:57:53 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.639300791556728 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6054882812500004 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6714603739982183 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.654011936339524 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.236763754045309 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2374433656957926 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.573216463414632 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.536655328798188 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.471982116244412 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.396593406593408 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.8239067524115775 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.79808153477218 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.371719901719904 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.195467625899294 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.390160098522182 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.194478417266169 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.338276283618572 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.143285714285724 ms/frame
02:57:53 INFO - PID 3823 |
02:57:53 INFO - PID 3823 | Cycle 1(2): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
02:57:55 INFO - PID 3823 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:58:39 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.672346393588602 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.615470588235295 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.664449378330373 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6531167108753313 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2328178879310348 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.225983870967743 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.594387442572739 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.5769107551487425 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.457949479940566 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.425597345132741 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.9513696369637 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.819542168674691 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.38940886699507 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.119145907473321 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.408395061728399 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.145696428571422 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.336136919315393 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.170681003584239 ms/frame
02:58:39 INFO - PID 3823 |
02:58:39 INFO - PID 3823 | Cycle 1(3): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
02:59:25 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6296538124452233 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6084354628422433 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.657444641275465 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6533156498673733 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2399244060475163 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2262661290322576 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.41279411764706 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.310937499999995 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.329704184704186 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.368034934497819 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.879073170731709 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.83227053140097 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.371253071253068 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.144125000000011 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.336699266503683 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.144214285714276 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.355122549019606 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.1208007117437715 ms/frame
02:59:25 INFO - PID 3823 |
02:59:25 INFO - PID 3823 | Cycle 1(4): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:00:11 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6345127304653206 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.618017015706807 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.676730597680642 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6534482758620688 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2475595238095227 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2312116316639754 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.329841269841269 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.264797441364602 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.3367268786127156 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.311487068965515 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.885977198697071 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.8785975609756065 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.373685503685504 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.14408928571429 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.317134146341467 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.092943262411344 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.391551724137934 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.144160714285707 ms/frame
03:00:11 INFO - PID 3823 |
03:00:11 INFO - PID 3823 | Cycle 1(5): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:00:57 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6186169284467713 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6085202086049546 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.662125110913931 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6463558201058195 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.258089033659067 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.226338709677419 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.305121951219515 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.273782051282046 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.540128593040844 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.44488888888889 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.862941653160456 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.797086330935248 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.33964547677261 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.0936524822695155 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.355122549019606 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.093226950354613 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.389347290640387 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.146232142857142 ms/frame
03:00:57 INFO - PID 3823 |
03:00:57 INFO - PID 3823 | Cycle 1(6): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:01:43 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6228758741258744 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.601638491547464 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.660035460992908 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6492317880794705 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2330226293103457 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2320920840064606 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.316791366906473 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.358300653594777 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.418335787923415 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.475078299776287 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.863411669367907 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.796438848920858 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.409728395061725 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.14355357142857 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.353272058823534 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.144053571428568 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.3554044117647 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.144357142857163 ms/frame
03:01:43 INFO - PID 3823 |
03:01:43 INFO - PID 3823 | Cycle 1(7): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:02:29 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6342405618964 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.611312010443864 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.671380231522707 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.656812749003984 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2399406047516193 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2375080906148876 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.3679694323144105 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.301505376344088 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.560645896656533 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.578741418764301 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.910278232405893 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.904215686274505 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.336601466992669 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.142964285714282 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.407790123456787 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.120124555160133 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.427289603960396 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.093953900709229 ms/frame
03:02:29 INFO - PID 3823 |
03:02:29 INFO - PID 3823 | Cycle 1(8): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:03:15 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6132534843205573 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6146209150326802 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6689991103202853 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6533222811671093 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2327316810344833 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.232673667205169 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.268271692745376 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.2825481798715215 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.567412480974126 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.505979729729727 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.943163097199341 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.8684184914841895 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.336466992665032 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.17062724014338 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.353100490196066 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.120925266903926 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.353186274509801 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.16894265232973 ms/frame
03:03:15 INFO - PID 3823 |
03:03:15 INFO - PID 3823 | Cycle 1(9): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:04:01 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6343107989464443 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6147581699346403 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.666684444444445 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.653852785145889 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.243654054054053 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2261048387096793 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.2800927246790295 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.23758474576271 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.425501474926251 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.31128232758621 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.862706645056722 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.8312922705314065 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.373022113022103 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.17062724014338 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.373120393120394 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.120427046263359 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.318719512195129 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.118380782918147 ms/frame
03:04:01 INFO - PID 3823 |
03:04:01 INFO - PID 3823 | Cycle 1(10): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:04:47 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.625559930008749 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6043945312499996 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.660075354609929 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.646501322751324 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.251110509209102 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.231938610662358 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.459078751857359 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.495707865168532 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.531986404833841 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.54726136363636 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.934432565789471 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.902426470588232 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.407456790123467 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.119555160142327 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.353835784313721 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.069028268551249 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.353296568627461 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.171164874551962 ms/frame
03:04:47 INFO - PID 3823 |
03:04:47 INFO - PID 3823 | Cycle 1(11): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:05:33 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_startup = 2.632254385964912 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6121279373368145 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6714113980409615 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6494304635761585 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2614782608695645 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.227274193548387 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_startup = 4.491609281437124 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1_inclip = 4.42511061946903 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.3810656934306555 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.386030701754383 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_startup = 4.84703554119548 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.480p.60fps.webm_scale_2_inclip = 4.774713603818615 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.427586633663364 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.120231316725991 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.335672371638144 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.14287499999999 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.303990267639908 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.068727915194336 ms/frame
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | Cycle 1(12): loaded http://localhost:53037/tests/video/video_playback.html (next: http://localhost:53037/tests/video/video_playback.html)
03:05:33 INFO - PID 3823 | __start_tp_report
03:05:33 INFO - PID 3823 | _x_x_mozilla_page_load
03:05:33 INFO - PID 3823 | _x_x_mozilla_page_load_details
03:05:33 INFO - PID 3823 | |i|pagename|runs|
03:05:33 INFO - PID 3823 | |0;240p.120fps.mp4_scale_1_startup;2.8120337394564205;2.639300791556728;2.672346393588602;2.6296538124452233;2.6345127304653206;2.6186169284467713;2.6228758741258744;2.6342405618964;2.6132534843205573;2.6343107989464443;2.625559930008749;2.632254385964912
03:05:33 INFO - PID 3823 | |1;240p.120fps.mp4_scale_1_inclip;2.605286458333333;2.6054882812500004;2.615470588235295;2.6084354628422433;2.618017015706807;2.6085202086049546;2.601638491547464;2.611312010443864;2.6146209150326802;2.6147581699346403;2.6043945312499996;2.6121279373368145
03:05:33 INFO - PID 3823 | |2;240p.120fps.mp4_scale_1.1_startup;2.664755772646536;2.6714603739982183;2.664449378330373;2.657444641275465;2.676730597680642;2.662125110913931;2.660035460992908;2.671380231522707;2.6689991103202853;2.666684444444445;2.660075354609929;2.6714113980409615
03:05:33 INFO - PID 3823 | |3;240p.120fps.mp4_scale_1.1_inclip;2.657177954847277;2.654011936339524;2.6531167108753313;2.6533156498673733;2.6534482758620688;2.6463558201058195;2.6492317880794705;2.656812749003984;2.6533222811671093;2.653852785145889;2.646501322751324;2.6494304635761585
03:05:33 INFO - PID 3823 | |4;240p.120fps.mp4_scale_2_startup;3.247364718614717;3.236763754045309;3.2328178879310348;3.2399244060475163;3.2475595238095227;3.258089033659067;3.2330226293103457;3.2399406047516193;3.2327316810344833;3.243654054054053;3.251110509209102;3.2614782608695645
03:05:33 INFO - PID 3823 | |5;240p.120fps.mp4_scale_2_inclip;3.2270645161290332;3.2374433656957926;3.225983870967743;3.2262661290322576;3.2312116316639754;3.226338709677419;3.2320920840064606;3.2375080906148876;3.232673667205169;3.2261048387096793;3.231938610662358;3.227274193548387
03:05:33 INFO - PID 3823 | |6;480p.60fps.webm_scale_1_startup;4.262251420454546;4.573216463414632;4.594387442572739;4.41279411764706;4.329841269841269;4.305121951219515;4.316791366906473;4.3679694323144105;4.268271692745376;4.2800927246790295;4.459078751857359;4.491609281437124
03:05:33 INFO - PID 3823 | |7;480p.60fps.webm_scale_1_inclip;4.266311300639662;4.536655328798188;4.5769107551487425;4.310937499999995;4.264797441364602;4.273782051282046;4.358300653594777;4.301505376344088;4.2825481798715215;4.23758474576271;4.495707865168532;4.42511061946903
03:05:33 INFO - PID 3823 | |8;480p.60fps.webm_scale_1.1_startup;4.3991715542522005;4.471982116244412;4.457949479940566;4.329704184704186;4.3367268786127156;4.540128593040844;4.418335787923415;4.560645896656533;4.567412480974126;4.425501474926251;4.531986404833841;4.3810656934306555
03:05:33 INFO - PID 3823 | |9;480p.60fps.webm_scale_1.1_inclip;4.4260730088495555;4.396593406593408;4.425597345132741;4.368034934497819;4.311487068965515;4.44488888888889;4.475078299776287;4.578741418764301;4.505979729729727;4.31128232758621;4.54726136363636;4.386030701754383
03:05:33 INFO - PID 3823 | |10;480p.60fps.webm_scale_2_startup;4.855671521035599;4.8239067524115775;4.9513696369637;4.879073170731709;4.885977198697071;4.862941653160456;4.863411669367907;4.910278232405893;4.943163097199341;4.862706645056722;4.934432565789471;4.84703554119548
03:05:33 INFO - PID 3823 | |11;480p.60fps.webm_scale_2_inclip;4.821240963855418;4.79808153477218;4.819542168674691;4.83227053140097;4.8785975609756065;4.797086330935248;4.796438848920858;4.904215686274505;4.8684184914841895;4.8312922705314065;4.902426470588232;4.774713603818615
03:05:33 INFO - PID 3823 | |12;1080p.60fps.mp4_scale_1_startup;7.407567901234556;7.371719901719904;7.38940886699507;7.371253071253068;7.373685503685504;7.33964547677261;7.409728395061725;7.336601466992669;7.336466992665032;7.373022113022103;7.407456790123467;7.427586633663364
03:05:33 INFO - PID 3823 | |13;1080p.60fps.mp4_scale_1_inclip;7.093421985815607;7.195467625899294;7.119145907473321;7.144125000000011;7.14408928571429;7.0936524822695155;7.14355357142857;7.142964285714282;7.17062724014338;7.17062724014338;7.119555160142327;7.120231316725991
03:05:33 INFO - PID 3823 | |14;1080p.60fps.mp4_scale_1.1_startup;7.301496350364966;7.390160098522182;7.408395061728399;7.336699266503683;7.317134146341467;7.355122549019606;7.353272058823534;7.407790123456787;7.353100490196066;7.373120393120394;7.353835784313721;7.335672371638144
03:05:33 INFO - PID 3823 | |15;1080p.60fps.mp4_scale_1.1_inclip;7.170286738351236;7.194478417266169;7.145696428571422;7.144214285714276;7.092943262411344;7.093226950354613;7.144053571428568;7.120124555160133;7.120925266903926;7.120427046263359;7.069028268551249;7.14287499999999
03:05:33 INFO - PID 3823 | |16;1080p.60fps.mp4_scale_2_startup;7.371547911547914;7.338276283618572;7.336136919315393;7.355122549019606;7.391551724137934;7.389347290640387;7.3554044117647;7.427289603960396;7.353186274509801;7.318719512195129;7.353296568627461;7.303990267639908
03:05:33 INFO - PID 3823 | |17;1080p.60fps.mp4_scale_2_inclip;7.169157706093189;7.143285714285724;7.170681003584239;7.1208007117437715;7.144160714285707;7.146232142857142;7.144357142857163;7.093953900709229;7.16894265232973;7.118380782918147;7.171164874551962;7.068727915194336
03:05:33 INFO - PID 3823 | __end_tp_report
03:05:33 INFO - PID 3823 | __start_cc_report
03:05:33 INFO - PID 3823 | _x_x_mozilla_cycle_collect,560
03:05:33 INFO - PID 3823 | __end_cc_report
03:05:33 INFO - PID 3823 | __startTimestamp1510743933497__endTimestamp
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | ------- Summary: start -------
03:05:33 INFO - PID 3823 | Number of tests: 18
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.65 Median:2.63 stddev:0.05 (2.0%) stddev-sans-first:0.02
03:05:33 INFO - PID 3823 | Values: 2.8 2.6 2.7 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.61 Median:2.61 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:05:33 INFO - PID 3823 | Values: 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.67 Median:2.67 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:05:33 INFO - PID 3823 | Values: 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.65 Median:2.65 stddev:0.00 (0.1%) stddev-sans-first:0.00
03:05:33 INFO - PID 3823 | Values: 2.7 2.7 2.7 2.7 2.7 2.6 2.6 2.7 2.7 2.7 2.6 2.6
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.24 Median:3.25 stddev:0.01 (0.3%) stddev-sans-first:0.01
03:05:33 INFO - PID 3823 | Values: 3.2 3.2 3.2 3.2 3.2 3.3 3.2 3.2 3.2 3.2 3.3 3.3
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.23 Median:3.23 stddev:0.00 (0.1%) stddev-sans-first:0.00
03:05:33 INFO - PID 3823 | Values: 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.39 Median:4.39 stddev:0.12 (2.7%) stddev-sans-first:0.12
03:05:33 INFO - PID 3823 | Values: 4.3 4.6 4.6 4.4 4.3 4.3 4.3 4.4 4.3 4.3 4.5 4.5
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.36 Median:4.33 stddev:0.12 (2.7%) stddev-sans-first:0.12
03:05:33 INFO - PID 3823 | Values: 4.3 4.5 4.6 4.3 4.3 4.3 4.4 4.3 4.3 4.2 4.5 4.4
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.45 Median:4.46 stddev:0.08 (1.9%) stddev-sans-first:0.09
03:05:33 INFO - PID 3823 | Values: 4.4 4.5 4.5 4.3 4.3 4.5 4.4 4.6 4.6 4.4 4.5 4.4
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.43 Median:4.44 stddev:0.08 (1.9%) stddev-sans-first:0.09
03:05:33 INFO - PID 3823 | Values: 4.4 4.4 4.4 4.4 4.3 4.4 4.5 4.6 4.5 4.3 4.5 4.4
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.88 Median:4.88 stddev:0.04 (0.8%) stddev-sans-first:0.04
03:05:33 INFO - PID 3823 | Values: 4.9 4.8 5.0 4.9 4.9 4.9 4.9 4.9 4.9 4.9 4.9 4.8
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.84 Median:4.83 stddev:0.04 (0.9%) stddev-sans-first:0.05
03:05:33 INFO - PID 3823 | Values: 4.8 4.8 4.8 4.8 4.9 4.8 4.8 4.9 4.9 4.8 4.9 4.8
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.38 Median:7.38 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:05:33 INFO - PID 3823 | Values: 7.4 7.4 7.4 7.4 7.4 7.3 7.4 7.3 7.3 7.4 7.4 7.4
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:7.14 Median:7.14 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:05:33 INFO - PID 3823 | Values: 7.1 7.2 7.1 7.1 7.1 7.1 7.1 7.1 7.2 7.2 7.1 7.1
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.36 Median:7.35 stddev:0.03 (0.5%) stddev-sans-first:0.03
03:05:33 INFO - PID 3823 | Values: 7.3 7.4 7.4 7.3 7.3 7.4 7.4 7.4 7.4 7.4 7.4 7.3
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:7.13 Median:7.14 stddev:0.03 (0.5%) stddev-sans-first:0.03
03:05:33 INFO - PID 3823 | Values: 7.2 7.2 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.36 Median:7.36 stddev:0.03 (0.5%) stddev-sans-first:0.04
03:05:33 INFO - PID 3823 | Values: 7.4 7.3 7.3 7.4 7.4 7.4 7.4 7.4 7.4 7.3 7.4 7.3
03:05:33 INFO - PID 3823 |
03:05:33 INFO - PID 3823 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:7.14 Median:7.15 stddev:0.03 (0.5%) stddev-sans-first:0.03
03:05:33 INFO - PID 3823 | Values: 7.2 7.1 7.2 7.1 7.1 7.1 7.1 7.1 7.2 7.1 7.2 7.1
03:05:33 INFO - PID 3823 | -------- Summary: end --------
03:05:33 INFO - PID 3823 |
03:05:34 INFO - TEST-INFO | 3823: exit 0
03:05:34 INFO - TEST-OK | basic_compositor_video | took 560933ms
03:05:34 INFO - TEST-START | glvideo
03:05:34 INFO - Initialising browser for glvideo test...
03:05:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53037/getInfo.html -profile /tmp/tmpl3qBGT/profile
03:05:34 INFO - TEST-INFO | started process 5096 (/builds/slave/test/build/application/firefox/firefox http://localhost:53037/getInfo.html)
03:05:40 INFO - TEST-INFO | 5096: exit 0
03:05:40 INFO - Browser initialized.
03:05:40 INFO - Running cycle 1/1 for glvideo test...
03:05:40 INFO - Using env: {'DISPLAY': ':0',
03:05:40 INFO - 'HOME': '/home/cltbld',
03:05:40 INFO - 'JSGC_DISABLE_POISONING': '1',
03:05:40 INFO - 'LANG': 'en_US.UTF-8',
03:05:40 INFO - 'LANGUAGE': 'en_US:en',
03:05:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:05:40 INFO - 'LOGNAME': 'cltbld',
03:05:40 INFO - 'MAIL': '/var/mail/cltbld',
03:05:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:05:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:05:40 INFO - 'MOZ_ACCELERATED': '1',
03:05:40 INFO - 'MOZ_CRASHREPORTER': '1',
03:05:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:05:40 INFO - 'MOZ_NO_REMOTE': '1',
03:05:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:05:40 INFO - 'MOZ_WEBRENDER': '1',
03:05:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:40 INFO - 'NO_EM_RESTART': '1',
03:05:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:40 INFO - 'PWD': '/builds/slave/test',
03:05:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:05:40 INFO - 'RUST_BACKTRACE': 'full',
03:05:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:05:40 INFO - 'SHELL': '/bin/bash',
03:05:40 INFO - 'SHLVL': '1',
03:05:40 INFO - 'TERM': 'linux',
03:05:40 INFO - 'TMOUT': '86400',
03:05:40 INFO - 'USER': 'cltbld',
03:05:40 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
03:05:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:40 INFO - '_': '/tools/buildbot/bin/python'}
03:05:40 INFO - TEST-INFO | started process 5376 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpl3qBGT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:05:41 INFO - PID 5376 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:05:41 INFO - PID 5376 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:05:41 INFO - PID 5376 |
03:05:41 INFO - PID 5376 | (/builds/slave/test/build/application/firefox/firefox:5440): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:05:41 INFO - PID 5376 |
03:05:42 INFO - PID 5376 | [talos glvideo result] Mean tick time across 100 ticks: 6.7661 ms
03:05:42 INFO - PID 5376 | Cycle 1(1): loaded http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html)
03:05:44 INFO - PID 5376 | [talos glvideo result] Mean tick time across 100 ticks: 6.497800000000001 ms
03:05:44 INFO - PID 5376 | Cycle 1(2): loaded http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html)
03:05:45 INFO - PID 5376 | [talos glvideo result] Mean tick time across 100 ticks: 6.61475 ms
03:05:45 INFO - PID 5376 | Cycle 1(3): loaded http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html)
03:05:47 INFO - PID 5376 | [talos glvideo result] Mean tick time across 100 ticks: 6.68515 ms
03:05:47 INFO - PID 5376 | Cycle 1(4): loaded http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html)
03:05:48 INFO - PID 5376 | [talos glvideo result] Mean tick time across 100 ticks: 6.732850000000001 ms
03:05:48 INFO - PID 5376 | Cycle 1(5): loaded http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53037/tests/webgl/benchmarks/video/video_upload.html)
03:05:48 INFO - PID 5376 | __start_tp_report
03:05:48 INFO - PID 5376 | _x_x_mozilla_page_load
03:05:48 INFO - PID 5376 | _x_x_mozilla_page_load_details
03:05:48 INFO - PID 5376 | |i|pagename|runs|
03:05:48 INFO - PID 5376 | |0;Mean tick time across 100 ticks: ;6.7661;6.497800000000001;6.61475;6.68515;6.732850000000001
03:05:48 INFO - PID 5376 | __end_tp_report
03:05:48 INFO - PID 5376 | __start_cc_report
03:05:48 INFO - PID 5376 | _x_x_mozilla_cycle_collect,276
03:05:48 INFO - PID 5376 | __end_cc_report
03:05:48 INFO - PID 5376 | __startTimestamp1510743948611__endTimestamp
03:05:48 INFO - PID 5376 |
03:05:48 INFO - PID 5376 | ------- Summary: start -------
03:05:48 INFO - PID 5376 | Number of tests: 1
03:05:48 INFO - PID 5376 |
03:05:48 INFO - PID 5376 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.66 Median:6.69 stddev:0.11 (1.6%) stddev-sans-first:0.10
03:05:48 INFO - PID 5376 | Values: 6.8 6.5 6.6 6.7 6.7
03:05:48 INFO - PID 5376 | -------- Summary: end --------
03:05:48 INFO - PID 5376 |
03:05:49 INFO - PID 5376 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:05:49 INFO - TEST-INFO | 5376: exit 0
03:05:49 INFO - TEST-OK | glvideo | took 15136ms
03:05:49 INFO - TEST-START | displaylist_mutate
03:05:49 INFO - Initialising browser for displaylist_mutate test...
03:05:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53037/getInfo.html -profile /tmp/tmpNHCrMm/profile
03:05:49 INFO - TEST-INFO | started process 5614 (/builds/slave/test/build/application/firefox/firefox http://localhost:53037/getInfo.html)
03:05:55 INFO - TEST-INFO | 5614: exit 0
03:05:55 INFO - Browser initialized.
03:05:55 INFO - Running cycle 1/1 for displaylist_mutate test...
03:05:55 INFO - Using env: {'DISPLAY': ':0',
03:05:55 INFO - 'HOME': '/home/cltbld',
03:05:55 INFO - 'JSGC_DISABLE_POISONING': '1',
03:05:55 INFO - 'LANG': 'en_US.UTF-8',
03:05:55 INFO - 'LANGUAGE': 'en_US:en',
03:05:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:05:55 INFO - 'LOGNAME': 'cltbld',
03:05:55 INFO - 'MAIL': '/var/mail/cltbld',
03:05:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:05:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:05:55 INFO - 'MOZ_ACCELERATED': '1',
03:05:55 INFO - 'MOZ_CRASHREPORTER': '1',
03:05:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:05:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:05:55 INFO - 'MOZ_NO_REMOTE': '1',
03:05:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:05:55 INFO - 'MOZ_WEBRENDER': '1',
03:05:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:05:55 INFO - 'NO_EM_RESTART': '1',
03:05:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:05:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:05:55 INFO - 'PWD': '/builds/slave/test',
03:05:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:05:55 INFO - 'RUST_BACKTRACE': 'full',
03:05:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:05:55 INFO - 'SHELL': '/bin/bash',
03:05:55 INFO - 'SHLVL': '1',
03:05:55 INFO - 'TERM': 'linux',
03:05:55 INFO - 'TMOUT': '86400',
03:05:55 INFO - 'USER': 'cltbld',
03:05:55 INFO - 'XDG_SESSION_COOKIE': '82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729',
03:05:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:05:55 INFO - '_': '/tools/buildbot/bin/python'}
03:05:55 INFO - TEST-INFO | started process 5890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNHCrMm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:05:56 INFO - PID 5890 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:05:56 INFO - PID 5890 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:05:57 INFO - PID 5890 |
03:05:57 INFO - PID 5890 | (/builds/slave/test/build/application/firefox/firefox:5952): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:05:57 INFO - PID 5890 |
03:06:20 INFO - PID 5890 | Cycle 1(1): loaded http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html)
03:06:44 INFO - PID 5890 | Cycle 1(2): loaded http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html)
03:07:09 INFO - PID 5890 | Cycle 1(3): loaded http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html)
03:07:33 INFO - PID 5890 | Cycle 1(4): loaded http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html)
03:07:33 INFO - PID 5890 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:07:58 INFO - PID 5890 | Cycle 1(5): loaded http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53037/tests/layout/benchmarks/displaylist_mutate.html)
03:07:58 INFO - PID 5890 | __start_tp_report
03:07:58 INFO - PID 5890 | _x_x_mozilla_page_load
03:07:58 INFO - PID 5890 | _x_x_mozilla_page_load_details
03:07:58 INFO - PID 5890 | |i|pagename|runs|
03:07:58 INFO - PID 5890 | |0;/displaylist_mutate.html;23148.39;23116.515;23564.36;23701.71;23824.535
03:07:58 INFO - PID 5890 | __end_tp_report
03:07:58 INFO - PID 5890 | __start_cc_report
03:07:58 INFO - PID 5890 | _x_x_mozilla_cycle_collect,221
03:07:58 INFO - PID 5890 | __end_cc_report
03:07:58 INFO - PID 5890 | __startTimestamp1510744078457__endTimestamp
03:07:58 INFO - PID 5890 |
03:07:58 INFO - PID 5890 | ------- Summary: start -------
03:07:58 INFO - PID 5890 | Number of tests: 1
03:07:58 INFO - PID 5890 |
03:07:58 INFO - PID 5890 | [#0] /displaylist_mutate.html Cycles:5 Average:23471.10 Median:23564.36 stddev:322.75 (1.4%) stddev-sans-first:309.02
03:07:58 INFO - PID 5890 | Values: 23148.4 23116.5 23564.4 23701.7 23824.5
03:07:58 INFO - PID 5890 | -------- Summary: end --------
03:07:58 INFO - PID 5890 |
03:07:59 INFO - TEST-INFO | 5890: exit 0
03:07:59 INFO - TEST-OK | displaylist_mutate | took 129816ms
03:07:59 INFO - SUITE-END | took 705s
03:07:59 INFO - Completed test suite (00:11:46)
03:07:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.5933174072200105, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.8120337394564205, 2.639300791556728, 2.672346393588602, 2.6296538124452233, 2.6345127304653206, 2.6186169284467713, 2.6228758741258744, 2.6342405618964, 2.6132534843205573, 2.6343107989464443, 2.625559930008749, 2.632254385964912], "value": 2.632254385964912, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.605286458333333, 2.6054882812500004, 2.615470588235295, 2.6084354628422433, 2.618017015706807, 2.6085202086049546, 2.601638491547464, 2.611312010443864, 2.6146209150326802, 2.6147581699346403, 2.6043945312499996, 2.6121279373368145], "value": 2.611312010443864, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.664755772646536, 2.6714603739982183, 2.664449378330373, 2.657444641275465, 2.676730597680642, 2.662125110913931, 2.660035460992908, 2.671380231522707, 2.6689991103202853, 2.666684444444445, 2.660075354609929, 2.6714113980409615], "value": 2.666684444444445, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.657177954847277, 2.654011936339524, 2.6531167108753313, 2.6533156498673733, 2.6534482758620688, 2.6463558201058195, 2.6492317880794705, 2.656812749003984, 2.6533222811671093, 2.653852785145889, 2.646501322751324, 2.6494304635761585], "value": 2.6533156498673733, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.247364718614717, 3.236763754045309, 3.2328178879310348, 3.2399244060475163, 3.2475595238095227, 3.258089033659067, 3.2330226293103457, 3.2399406047516193, 3.2327316810344833, 3.243654054054053, 3.251110509209102, 3.2614782608695645], "value": 3.2399406047516193, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.2270645161290332, 3.2374433656957926, 3.225983870967743, 3.2262661290322576, 3.2312116316639754, 3.226338709677419, 3.2320920840064606, 3.2375080906148876, 3.232673667205169, 3.2261048387096793, 3.231938610662358, 3.227274193548387], "value": 3.2312116316639754, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.262251420454546, 4.573216463414632, 4.594387442572739, 4.41279411764706, 4.329841269841269, 4.305121951219515, 4.316791366906473, 4.3679694323144105, 4.268271692745376, 4.2800927246790295, 4.459078751857359, 4.491609281437124], "value": 4.3679694323144105, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.266311300639662, 4.536655328798188, 4.5769107551487425, 4.310937499999995, 4.264797441364602, 4.273782051282046, 4.358300653594777, 4.301505376344088, 4.2825481798715215, 4.23758474576271, 4.495707865168532, 4.42511061946903], "value": 4.310937499999995, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.3991715542522005, 4.471982116244412, 4.457949479940566, 4.329704184704186, 4.3367268786127156, 4.540128593040844, 4.418335787923415, 4.560645896656533, 4.567412480974126, 4.425501474926251, 4.531986404833841, 4.3810656934306555], "value": 4.457949479940566, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.4260730088495555, 4.396593406593408, 4.425597345132741, 4.368034934497819, 4.311487068965515, 4.44488888888889, 4.475078299776287, 4.578741418764301, 4.505979729729727, 4.31128232758621, 4.54726136363636, 4.386030701754383], "value": 4.425597345132741, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.855671521035599, 4.8239067524115775, 4.9513696369637, 4.879073170731709, 4.885977198697071, 4.862941653160456, 4.863411669367907, 4.910278232405893, 4.943163097199341, 4.862706645056722, 4.934432565789471, 4.84703554119548], "value": 4.879073170731709, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.821240963855418, 4.79808153477218, 4.819542168674691, 4.83227053140097, 4.8785975609756065, 4.797086330935248, 4.796438848920858, 4.904215686274505, 4.8684184914841895, 4.8312922705314065, 4.902426470588232, 4.774713603818615], "value": 4.8312922705314065, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.407567901234556, 7.371719901719904, 7.38940886699507, 7.371253071253068, 7.373685503685504, 7.33964547677261, 7.409728395061725, 7.336601466992669, 7.336466992665032, 7.373022113022103, 7.407456790123467, 7.427586633663364], "value": 7.373022113022103, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.093421985815607, 7.195467625899294, 7.119145907473321, 7.144125000000011, 7.14408928571429, 7.0936524822695155, 7.14355357142857, 7.142964285714282, 7.17062724014338, 7.17062724014338, 7.119555160142327, 7.120231316725991], "value": 7.14355357142857, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.301496350364966, 7.390160098522182, 7.408395061728399, 7.336699266503683, 7.317134146341467, 7.355122549019606, 7.353272058823534, 7.407790123456787, 7.353100490196066, 7.373120393120394, 7.353835784313721, 7.335672371638144], "value": 7.353835784313721, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.170286738351236, 7.194478417266169, 7.145696428571422, 7.144214285714276, 7.092943262411344, 7.093226950354613, 7.144053571428568, 7.120124555160133, 7.120925266903926, 7.120427046263359, 7.069028268551249, 7.14287499999999], "value": 7.120925266903926, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.371547911547914, 7.338276283618572, 7.336136919315393, 7.355122549019606, 7.391551724137934, 7.389347290640387, 7.3554044117647, 7.427289603960396, 7.353186274509801, 7.318719512195129, 7.353296568627461, 7.303990267639908], "value": 7.353296568627461, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.169157706093189, 7.143285714285724, 7.170681003584239, 7.1208007117437715, 7.144160714285707, 7.146232142857142, 7.144357142857163, 7.093953900709229, 7.16894265232973, 7.118380782918147, 7.171164874551962, 7.068727915194336], "value": 7.144160714285707, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.7661, 6.497800000000001, 6.61475, 6.68515, 6.732850000000001], "value": 6.6499500000000005, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [23148.39, 23116.515, 23564.36, 23701.71, 23824.535], "value": 23633.035, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
03:07:59 INFO - Return code: 0
03:07:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:07:59 INFO - # TBPL SUCCESS #
03:07:59 INFO - Running post-action listener: _package_coverage_data
03:07:59 INFO - Running post-action listener: _resource_record_post_action
03:07:59 INFO - [mozharness: 2017-11-15 11:07:59.870653Z] Finished run-tests step (success)
03:07:59 INFO - Running post-run listener: _resource_record_post_run
03:07:59 INFO - Total resource usage - Wall time: 726s; CPU: 42.0%; Read bytes: 1662976; Write bytes: 352772096; Read time: 2304; Write time: 274468
03:07:59 INFO - TinderboxPrint: CPU usage
41.8%
03:07:59 INFO - TinderboxPrint: I/O read bytes / time
1,662,976 / 2,304
03:07:59 INFO - TinderboxPrint: I/O write bytes / time
352,772,096 / 274,468
03:07:59 INFO - TinderboxPrint: CPU idle
3,193.8 (58.0%)
03:07:59 INFO - TinderboxPrint: CPU system
665.5 (12.1%)
03:07:59 INFO - TinderboxPrint: CPU user
1,617.8 (29.4%)
03:07:59 INFO - TinderboxPrint: Swap in / out
0 / 0
03:07:59 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 7204864; Read time: 0; Write time: 5220
03:07:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:07:59 INFO - run-tests - Wall time: 707s; CPU: 43.0%; Read bytes: 1662976; Write bytes: 345554944; Read time: 2304; Write time: 269248
03:08:00 INFO - Running post-run listener: _upload_blobber_files
03:08:00 INFO - Blob upload gear active.
03:08:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:08:00 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
03:08:00 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']
03:08:00 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
03:08:00 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
03:08:00 INFO - SNIMissingWarning
03:08:00 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
03:08:00 INFO - InsecurePlatformWarning
03:08:00 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.)
03:08:00 INFO - SubjectAltNameWarning
03:08:00 INFO - (blobuploader) - INFO - Open directory for files ...
03:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:00 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
03:08:00 INFO - InsecurePlatformWarning
03:08:00 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.)
03:08:00 INFO - SubjectAltNameWarning
03:08:01 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
03:08:01 INFO - InsecurePlatformWarning
03:08:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:08:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:01 INFO - (blobuploader) - INFO - Done attempting.
03:08:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:08:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:01 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:01 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
03:08:01 INFO - InsecurePlatformWarning
03:08:01 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.)
03:08:01 INFO - SubjectAltNameWarning
03:08:04 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
03:08:04 INFO - InsecurePlatformWarning
03:08:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:08:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:04 INFO - (blobuploader) - INFO - Done attempting.
03:08:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:08:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:04 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
03:08:04 INFO - InsecurePlatformWarning
03:08:04 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.)
03:08:04 INFO - SubjectAltNameWarning
03:08:05 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
03:08:05 INFO - InsecurePlatformWarning
03:08:05 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:08:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:05 INFO - (blobuploader) - INFO - Done attempting.
03:08:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:08:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:05 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:05 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
03:08:05 INFO - InsecurePlatformWarning
03:08:05 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.)
03:08:05 INFO - SubjectAltNameWarning
03:08:06 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
03:08:06 INFO - InsecurePlatformWarning
03:08:06 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:08:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:06 INFO - (blobuploader) - INFO - Done attempting.
03:08:06 INFO - (blobuploader) - INFO - Iteration through files over.
03:08:06 INFO - Return code: 0
03:08:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:08:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:08:06 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a6e384bb548b3d4d1be3aeec335dbe241d639a6558262dc6cabb257e254f93782cce1d2e96d6ec2e60ff73080e1766bdb7438630eee48444fded5d587e347e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c0a11b95f293e26d4bf00345fca116ca0a96db87b08426d8a1810d0aea26f2376b80d6413dc8257543c33ef1ab9afad3d76ff9248e32dd7fa32cf6a01f0b7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2875f200e5f93efa00e66be5c2e3de9b1658ef357a4b678a101e0d20194cea59272ca38c451ec6439a77b7a922130acd3bf0e7d73c3373876a377a1d39bab2c", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7271c86dbf11c276e1eba35cd3ad456773349652c353d840fa4e631633e0c7a12545467a5d06e55996785b867c791dd150b1aeafd56df99dbfba729f951320e2"}
03:08:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:08:06 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:08:06 INFO - Contents:
03:08:06 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a6e384bb548b3d4d1be3aeec335dbe241d639a6558262dc6cabb257e254f93782cce1d2e96d6ec2e60ff73080e1766bdb7438630eee48444fded5d587e347e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c0a11b95f293e26d4bf00345fca116ca0a96db87b08426d8a1810d0aea26f2376b80d6413dc8257543c33ef1ab9afad3d76ff9248e32dd7fa32cf6a01f0b7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2875f200e5f93efa00e66be5c2e3de9b1658ef357a4b678a101e0d20194cea59272ca38c451ec6439a77b7a922130acd3bf0e7d73c3373876a377a1d39bab2c", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7271c86dbf11c276e1eba35cd3ad456773349652c353d840fa4e631633e0c7a12545467a5d06e55996785b867c791dd150b1aeafd56df99dbfba729f951320e2"}
03:08:06 INFO - Running post-run listener: copy_logs_to_upload_dir
03:08:06 INFO - Copying logs to upload dir...
03:08:06 INFO - mkdir: /builds/slave/test/build/upload/logs
03:08:06 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=787.162709
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 13 mins, 7 secs) (at 2017-11-15 03:08:06.546345) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-15 03:08:06.549886) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729
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/8a6e384bb548b3d4d1be3aeec335dbe241d639a6558262dc6cabb257e254f93782cce1d2e96d6ec2e60ff73080e1766bdb7438630eee48444fded5d587e347e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c0a11b95f293e26d4bf00345fca116ca0a96db87b08426d8a1810d0aea26f2376b80d6413dc8257543c33ef1ab9afad3d76ff9248e32dd7fa32cf6a01f0b7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2875f200e5f93efa00e66be5c2e3de9b1658ef357a4b678a101e0d20194cea59272ca38c451ec6439a77b7a922130acd3bf0e7d73c3373876a377a1d39bab2c", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7271c86dbf11c276e1eba35cd3ad456773349652c353d840fa4e631633e0c7a12545467a5d06e55996785b867c791dd150b1aeafd56df99dbfba729f951320e2"}
build_url:https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011463
build_url: 'https://queue.taskcluster.net/v1/task/FG8YybqTSm6IxeVOoFGbcA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a6e384bb548b3d4d1be3aeec335dbe241d639a6558262dc6cabb257e254f93782cce1d2e96d6ec2e60ff73080e1766bdb7438630eee48444fded5d587e347e9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/476c0a11b95f293e26d4bf00345fca116ca0a96db87b08426d8a1810d0aea26f2376b80d6413dc8257543c33ef1ab9afad3d76ff9248e32dd7fa32cf6a01f0b7", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2875f200e5f93efa00e66be5c2e3de9b1658ef357a4b678a101e0d20194cea59272ca38c451ec6439a77b7a922130acd3bf0e7d73c3373876a377a1d39bab2c", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7271c86dbf11c276e1eba35cd3ad456773349652c353d840fa4e631633e0c7a12545467a5d06e55996785b867c791dd150b1aeafd56df99dbfba729f951320e2"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-15 03:08:06.582682) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-15 03:08:06.583392) =========
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=82c74bd3bc9a11c5833d2010000001e6-1510743296.476469-1886871729
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004536
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-15 03:08:06.670011) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-15 03:08:06.670726) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-15 03:08:06.671392) =========
========= Total master_lag: 0.22 =========