builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-030
starttime: 1512040382.29
results: success (0)
revision: ba283baf4e98aa3a5f45a17981077b98697aa73a
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.294233) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.294638) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.294898) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.383748) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.384028) =========
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=3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-30 03:13:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 80.6M=0s
2017-11-30 03:13:02 (80.6 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.231248
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.685564) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.685876) =========
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=3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.017673
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:02.735952) =========
========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-11-30 03:13:02.736264) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ba283baf4e98aa3a5f45a17981077b98697aa73a --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 ba283baf4e98aa3a5f45a17981077b98697aa73a --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=3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-30 03:13:02,792 truncating revision to first 12 chars
2017-11-30 03:13:02,792 Setting DEBUG logging.
2017-11-30 03:13:02,792 attempt 1/10
2017-11-30 03:13:02,792 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ba283baf4e98?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-30 03:13:03,718 unpacking tar archive at: mozilla-central-ba283baf4e98/testing/mozharness/
program finished with exit code 0
elapsedTime=2.005311
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-11-30 03:13:04.762562) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:04.762916) =========
script_repo_revision: ba283baf4e98aa3a5f45a17981077b98697aa73a
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:04.763361) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:04.764073) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-30 03:13:04.779914) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 17 secs) (at 2017-11-30 03:13:04.780317) =========
/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=3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:13:05 INFO - MultiFileLogger online at 20171130 03:13:05 in /builds/slave/test
03:13:05 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
03:13:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:13:05 INFO - {'append_to_log': False,
03:13:05 INFO - 'base_work_dir': '/builds/slave/test',
03:13:05 INFO - 'blob_upload_branch': 'Firefox',
03:13:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:13:05 INFO - 'branch': 'Firefox',
03:13:05 INFO - 'buildbot_json_path': 'buildprops.json',
03:13:05 INFO - 'code_coverage': False,
03:13:05 INFO - 'config_files': ('talos/linux_config.py',),
03:13:05 INFO - 'default_actions': ('clobber',
03:13:05 INFO - 'read-buildbot-config',
03:13:05 INFO - 'download-and-extract',
03:13:05 INFO - 'populate-webroot',
03:13:05 INFO - 'create-virtualenv',
03:13:05 INFO - 'install',
03:13:05 INFO - 'setup-mitmproxy',
03:13:05 INFO - 'run-tests'),
03:13:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:13:05 INFO - 'disable_ccov_upload': False,
03:13:05 INFO - 'disable_stylo': False,
03:13:05 INFO - 'download_minidump_stackwalk': True,
03:13:05 INFO - 'download_symbols': 'ondemand',
03:13:05 INFO - 'e10s': False,
03:13:05 INFO - 'enable_stylo': False,
03:13:05 INFO - 'enable_webrender': False,
03:13:05 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:13:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:13:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:13:05 INFO - 'gecko_profile': False,
03:13:05 INFO - 'gecko_profile_interval': 0,
03:13:05 INFO - 'installer_path': 'installer.exe',
03:13:05 INFO - 'jsd_code_coverage': False,
03:13:05 INFO - 'log_level': 'info',
03:13:05 INFO - 'log_name': 'talos',
03:13:05 INFO - 'log_to_console': True,
03:13:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:13:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:13:05 INFO - 'opt_config_files': (),
03:13:05 INFO - 'pip_index': False,
03:13:05 INFO - 'suite': 'g4-e10s',
03:13:05 INFO - 'system_bits': '32',
03:13:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:13:05 INFO - 'title': 'talos-linux64-ix-030',
03:13:05 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:13:05 INFO - 'use_talos_json': True,
03:13:05 INFO - 'verify': 'False',
03:13:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:13:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:13:05 INFO - 'work_dir': 'build'}
03:13:05 INFO - [mozharness: 2017-11-30 11:13:05.043439Z] Running clobber step.
03:13:05 INFO - Running pre-action listener: _resource_record_pre_action
03:13:05 INFO - Running main action method: clobber
03:13:05 INFO - rmtree: /builds/slave/test/build
03:13:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:13:06 INFO - Running post-action listener: _resource_record_post_action
03:13:06 INFO - [mozharness: 2017-11-30 11:13:06.049124Z] Finished clobber step (success)
03:13:06 INFO - [mozharness: 2017-11-30 11:13:06.049217Z] Running read-buildbot-config step.
03:13:06 INFO - Running pre-action listener: _resource_record_pre_action
03:13:06 INFO - Running main action method: read_buildbot_config
03:13:06 INFO - Using buildbot properties:
03:13:06 INFO - {
03:13:06 INFO - "project": "",
03:13:06 INFO - "product": "firefox",
03:13:06 INFO - "who": "shindli@mozilla.com",
03:13:06 INFO - "installer_path": "public/build/target.tar.bz2",
03:13:06 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
03:13:06 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:13:06 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
03:13:06 INFO - "stage_platform": "linux64-qr",
03:13:06 INFO - "basedir": "/builds/slave/test",
03:13:06 INFO - "buildnumber": 248,
03:13:06 INFO - "platform": "ubuntu64_hw_qr",
03:13:06 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
03:13:06 INFO - "slavebuilddir": "test",
03:13:06 INFO - "taskId": "Ib8T0xAQQmCM2ycyQhHBwA",
03:13:06 INFO - "branch": "mozilla-central",
03:13:06 INFO - "script_repo_revision": "production",
03:13:06 INFO - "revision": "ba283baf4e98aa3a5f45a17981077b98697aa73a",
03:13:06 INFO - "slavename": "talos-linux64-ix-030",
03:13:06 INFO - "repo_path": "mozilla-central"
03:13:06 INFO - }
03:13:06 INFO - Finding installer, test and symbols from parent task.
03:13:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ib8T0xAQQmCM2ycyQhHBwA'}, attempt #1
03:13:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ib8T0xAQQmCM2ycyQhHBwA'}, attempt #1
03:13:06 INFO - Task dependencies: JPrtCsqNTYWzJADHdO5gVw
03:13:06 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw'}, attempt #1
03:13:07 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2
03:13:07 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.test_packages.json
03:13:07 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.crashreporter-symbols.zip
03:13:07 INFO - Running post-action listener: _resource_record_post_action
03:13:07 INFO - [mozharness: 2017-11-30 11:13:07.405209Z] Finished read-buildbot-config step (success)
03:13:07 INFO - [mozharness: 2017-11-30 11:13:07.405519Z] Running download-and-extract step.
03:13:07 INFO - Running pre-action listener: _resource_record_pre_action
03:13:07 INFO - Running main action method: download_and_extract
03:13:07 INFO - mkdir: /builds/slave/test/build/tests
03:13:07 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')]}
03:13:07 INFO - trying https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.test_packages.json
03:13:07 INFO - Downloading https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:13:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:13:08 INFO - Downloaded 989 bytes.
03:13:08 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:13:08 INFO - Using the following test package requirements:
03:13:08 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:13:08 INFO - u'common': [u'target.common.tests.zip'],
03:13:08 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:13:08 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:13:08 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:13:08 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:13:08 INFO - u'mozbase': [u'target.common.tests.zip'],
03:13:08 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:13:08 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:13:08 INFO - u'web-platform': [u'target.common.tests.zip',
03:13:08 INFO - u'target.web-platform.tests.tar.gz'],
03:13:08 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:13:08 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:13:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.common.tests.zip
03:13:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:13:08 INFO - Fetch https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.common.tests.zip into memory
03:13:09 INFO - Content-Length response header: 59346668
03:13:09 INFO - Bytes received: 59346668
03:13:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.talos.tests.zip
03:13:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:13:20 INFO - Fetch https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.talos.tests.zip into memory
03:13:21 INFO - Content-Length response header: 18744804
03:13:21 INFO - Bytes received: 18744804
03:13: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')]}
03:13:22 INFO - trying https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2
03:13:22 INFO - Downloading https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:13:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:13:23 INFO - Downloaded 62751960 bytes.
03:13:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2
03:13:23 INFO - mkdir: /builds/slave/test/properties
03:13:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:13:23 INFO - Writing to file /builds/slave/test/properties/build_url
03:13:23 INFO - Contents:
03:13:23 INFO - build_url:https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2
03:13:23 INFO - Running post-action listener: _resource_record_post_action
03:13:23 INFO - Running post-action listener: find_tests_for_verification
03:13:23 INFO - Running post-action listener: set_extra_try_arguments
03:13:23 INFO - [mozharness: 2017-11-30 11:13:23.434708Z] Finished download-and-extract step (success)
03:13:23 INFO - [mozharness: 2017-11-30 11:13:23.434801Z] Running populate-webroot step.
03:13:23 INFO - Running pre-action listener: _resource_record_pre_action
03:13:23 INFO - Running main action method: populate_webroot
03:13:23 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:13:23 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['tresize',
03:13:23 INFO - 'tcanvasmark']},
03:13:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:13:23 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['dromaeo_css',
03:13:23 INFO - 'kraken']},
03:13:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:13:23 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:13:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'tests': ['damp', 'tps']},
03:13:23 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['damp', 'tps']},
03:13:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:13:23 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['dromaeo_dom']},
03:13:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
03:13:23 INFO - 'glvideo',
03:13:23 INFO - 'displaylist_mutate']},
03:13:23 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['basic_compositor_video',
03:13:23 INFO - 'glvideo']},
03:13:23 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:13:23 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['ts_paint_webext',
03:13:23 INFO - 'tp5o_webext']},
03:13:23 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:13:23 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:23 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:23 INFO - 'talos_options': ['--mitmproxy',
03:13:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:23 INFO - '--firstNonBlankPaint'],
03:13:23 INFO - 'tests': ['tp6_google_heavy',
03:13:23 INFO - 'tp6_youtube_heavy',
03:13:23 INFO - 'tp6_amazon_heavy',
03:13:23 INFO - 'tp6_facebook_heavy']},
03:13:23 INFO - 'other-e10s': {'tests': ['a11yr',
03:13:23 INFO - 'ts_paint',
03:13:23 INFO - 'tpaint',
03:13:23 INFO - 'sessionrestore',
03:13:23 INFO - 'sessionrestore_many_windows',
03:13:23 INFO - 'sessionrestore_no_auto_restore',
03:13:23 INFO - 'tabpaint',
03:13:23 INFO - 'cpstartup']},
03:13:23 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['a11yr',
03:13:23 INFO - 'ts_paint',
03:13:23 INFO - 'tpaint',
03:13:23 INFO - 'sessionrestore',
03:13:23 INFO - 'sessionrestore_many_windows',
03:13:23 INFO - 'sessionrestore_no_auto_restore',
03:13:23 INFO - 'tabpaint',
03:13:23 INFO - 'cpstartup']},
03:13:23 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:13:23 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:13:23 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['perf_reftest_singletons']},
03:13:23 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['perf_reftest']},
03:13:23 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:13:23 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['speedometer']},
03:13:23 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:13:23 INFO - 'tsvgr_opacity',
03:13:23 INFO - 'tart',
03:13:23 INFO - 'tscrollx',
03:13:23 INFO - 'tsvg_static']},
03:13:23 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['tsvgx',
03:13:23 INFO - 'tsvgr_opacity',
03:13:23 INFO - 'tart',
03:13:23 INFO - 'tscrollx',
03:13:23 INFO - 'tsvg_static']},
03:13:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:13:23 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'talos_options': ['--disable-stylo'],
03:13:23 INFO - 'tests': ['tp5o']},
03:13:23 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:23 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:23 INFO - 'talos_options': ['--mitmproxy',
03:13:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:23 INFO - '--firstNonBlankPaint'],
03:13:23 INFO - 'tests': ['tp6_google',
03:13:23 INFO - 'tp6_youtube',
03:13:23 INFO - 'tp6_amazon',
03:13:23 INFO - 'tp6_facebook']},
03:13:23 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:23 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:23 INFO - 'talos_options': ['--disable-stylo',
03:13:23 INFO - '--mitmproxy',
03:13:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:23 INFO - '--firstNonBlankPaint'],
03:13:23 INFO - 'tests': ['tp6_google',
03:13:23 INFO - 'tp6_youtube',
03:13:23 INFO - 'tp6_amazon',
03:13:23 INFO - 'tp6_facebook']},
03:13:23 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:13:23 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:13:23 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:13:23 INFO - 'talos_options': ['--stylo-threads=1',
03:13:23 INFO - '--mitmproxy',
03:13:23 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:13:23 INFO - '--firstNonBlankPaint'],
03:13:23 INFO - 'tests': ['tp6_google',
03:13:23 INFO - 'tp6_youtube',
03:13:23 INFO - 'tp6_amazon',
03:13:23 INFO - 'tp6_facebook']},
03:13:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'talos_options': ['--xperf_path',
03:13:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:13:23 INFO - 'tests': ['tp5n']},
03:13:23 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:13:23 INFO - 'talos_options': ['--disable-stylo',
03:13:23 INFO - '--xperf_path',
03:13:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:13:23 INFO - 'tests': ['tp5n']}}}
03:13:23 INFO - Running post-action listener: _resource_record_post_action
03:13:23 INFO - [mozharness: 2017-11-30 11:13:23.453209Z] Finished populate-webroot step (success)
03:13:23 INFO - [mozharness: 2017-11-30 11:13:23.453303Z] Running create-virtualenv step.
03:13:23 INFO - Running pre-action listener: _resource_record_pre_action
03:13:23 INFO - Running main action method: create_virtualenv
03:13:23 INFO - Creating virtualenv /builds/slave/test/build/venv
03:13:23 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
03:13:23 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:13:23 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:13:25 INFO - Using real prefix '/usr'
03:13:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:13:26 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:13:28 INFO - Installing setuptools, pip, wheel...done.
03:13:28 INFO - Return code: 0
03:13:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:28 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 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:28 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
03:13:28 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
03:13:28 INFO - Using env: {'DISPLAY': ':0',
03:13:28 INFO - 'HOME': '/home/cltbld',
03:13:28 INFO - 'LANG': 'en_US.UTF-8',
03:13:28 INFO - 'LANGUAGE': 'en_US:en',
03:13:28 INFO - 'LOGNAME': 'cltbld',
03:13:28 INFO - 'MAIL': '/var/mail/cltbld',
03:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:28 INFO - 'MOZ_NO_REMOTE': '1',
03:13:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:28 INFO - 'NO_EM_RESTART': '1',
03:13:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:28 INFO - 'PWD': '/builds/slave/test',
03:13:28 INFO - 'SHELL': '/bin/bash',
03:13:28 INFO - 'SHLVL': '1',
03:13:28 INFO - 'TERM': 'linux',
03:13:28 INFO - 'TMOUT': '86400',
03:13:28 INFO - 'USER': 'cltbld',
03:13:28 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:28 INFO - '_': '/tools/buildbot/bin/python'}
03:13:28 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:13:28 INFO - Return code: 0
03:13:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:28 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 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:28 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
03:13:28 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
03:13:28 INFO - Using env: {'DISPLAY': ':0',
03:13:28 INFO - 'HOME': '/home/cltbld',
03:13:28 INFO - 'LANG': 'en_US.UTF-8',
03:13:28 INFO - 'LANGUAGE': 'en_US:en',
03:13:28 INFO - 'LOGNAME': 'cltbld',
03:13:28 INFO - 'MAIL': '/var/mail/cltbld',
03:13:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:28 INFO - 'MOZ_NO_REMOTE': '1',
03:13:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:28 INFO - 'NO_EM_RESTART': '1',
03:13:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:28 INFO - 'PWD': '/builds/slave/test',
03:13:28 INFO - 'SHELL': '/bin/bash',
03:13:28 INFO - 'SHLVL': '1',
03:13:28 INFO - 'TERM': 'linux',
03:13:28 INFO - 'TMOUT': '86400',
03:13:28 INFO - 'USER': 'cltbld',
03:13:28 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:28 INFO - '_': '/tools/buildbot/bin/python'}
03:13:29 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:29 INFO - Collecting psutil>=3.1.1
03:13:30 INFO - Installing collected packages: psutil
03:13:30 INFO - Successfully installed psutil-3.1.1
03:13:30 INFO - Return code: 0
03:13:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
03:13:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
03:13:30 INFO - Using env: {'DISPLAY': ':0',
03:13:30 INFO - 'HOME': '/home/cltbld',
03:13:30 INFO - 'LANG': 'en_US.UTF-8',
03:13:30 INFO - 'LANGUAGE': 'en_US:en',
03:13:30 INFO - 'LOGNAME': 'cltbld',
03:13:30 INFO - 'MAIL': '/var/mail/cltbld',
03:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:30 INFO - 'MOZ_NO_REMOTE': '1',
03:13:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:30 INFO - 'NO_EM_RESTART': '1',
03:13:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:30 INFO - 'PWD': '/builds/slave/test',
03:13:30 INFO - 'SHELL': '/bin/bash',
03:13:30 INFO - 'SHLVL': '1',
03:13:30 INFO - 'TERM': 'linux',
03:13:30 INFO - 'TMOUT': '86400',
03:13:30 INFO - 'USER': 'cltbld',
03:13:30 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:30 INFO - '_': '/tools/buildbot/bin/python'}
03:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:30 INFO - Collecting mozsystemmonitor==0.3
03:13:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:13:31 INFO - Installing collected packages: mozsystemmonitor
03:13:31 INFO - Successfully installed mozsystemmonitor-0.3
03:13:31 INFO - Return code: 0
03:13:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:13: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')]}
03:13:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13: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')]}
03:13:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
03:13:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
03:13:31 INFO - Using env: {'DISPLAY': ':0',
03:13:31 INFO - 'HOME': '/home/cltbld',
03:13:31 INFO - 'LANG': 'en_US.UTF-8',
03:13:31 INFO - 'LANGUAGE': 'en_US:en',
03:13:31 INFO - 'LOGNAME': 'cltbld',
03:13:31 INFO - 'MAIL': '/var/mail/cltbld',
03:13:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:31 INFO - 'MOZ_NO_REMOTE': '1',
03:13:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:31 INFO - 'NO_EM_RESTART': '1',
03:13:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:31 INFO - 'PWD': '/builds/slave/test',
03:13:31 INFO - 'SHELL': '/bin/bash',
03:13:31 INFO - 'SHLVL': '1',
03:13:31 INFO - 'TERM': 'linux',
03:13:31 INFO - 'TMOUT': '86400',
03:13:31 INFO - 'USER': 'cltbld',
03:13:31 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:31 INFO - '_': '/tools/buildbot/bin/python'}
03:13:32 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:32 INFO - Collecting jsonschema==2.5.1
03:13:33 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:13:33 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:13:34 INFO - Installing collected packages: functools32, jsonschema
03:13:34 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:13:34 INFO - Return code: 0
03:13:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:13:34 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')]}
03:13:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:34 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')]}
03:13:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:34 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 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:34 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
03:13:34 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
03:13:34 INFO - Using env: {'DISPLAY': ':0',
03:13:34 INFO - 'HOME': '/home/cltbld',
03:13:34 INFO - 'LANG': 'en_US.UTF-8',
03:13:34 INFO - 'LANGUAGE': 'en_US:en',
03:13:34 INFO - 'LOGNAME': 'cltbld',
03:13:34 INFO - 'MAIL': '/var/mail/cltbld',
03:13:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:34 INFO - 'MOZ_NO_REMOTE': '1',
03:13:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:34 INFO - 'NO_EM_RESTART': '1',
03:13:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:34 INFO - 'PWD': '/builds/slave/test',
03:13:34 INFO - 'SHELL': '/bin/bash',
03:13:34 INFO - 'SHLVL': '1',
03:13:34 INFO - 'TERM': 'linux',
03:13:34 INFO - 'TMOUT': '86400',
03:13:34 INFO - 'USER': 'cltbld',
03:13:34 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:34 INFO - '_': '/tools/buildbot/bin/python'}
03:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:13:35 INFO - Return code: 0
03:13:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
03:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
03:13:35 INFO - Using env: {'DISPLAY': ':0',
03:13:35 INFO - 'HOME': '/home/cltbld',
03:13:35 INFO - 'LANG': 'en_US.UTF-8',
03:13:35 INFO - 'LANGUAGE': 'en_US:en',
03:13:35 INFO - 'LOGNAME': 'cltbld',
03:13:35 INFO - 'MAIL': '/var/mail/cltbld',
03:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:35 INFO - 'MOZ_NO_REMOTE': '1',
03:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:35 INFO - 'NO_EM_RESTART': '1',
03:13:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:35 INFO - 'PWD': '/builds/slave/test',
03:13:35 INFO - 'SHELL': '/bin/bash',
03:13:35 INFO - 'SHLVL': '1',
03:13:35 INFO - 'TERM': 'linux',
03:13:35 INFO - 'TMOUT': '86400',
03:13:35 INFO - 'USER': 'cltbld',
03:13:35 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:35 INFO - '_': '/tools/buildbot/bin/python'}
03:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:35 INFO - Collecting blobuploader==1.2.4
03:13:36 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:13:37 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:13:38 INFO - Installing collected packages: requests, docopt, blobuploader
03:13:38 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:13:38 INFO - Return code: 0
03:13:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:38 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 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:38 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
03:13:38 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
03:13:38 INFO - Using env: {'DISPLAY': ':0',
03:13:38 INFO - 'HOME': '/home/cltbld',
03:13:38 INFO - 'LANG': 'en_US.UTF-8',
03:13:38 INFO - 'LANGUAGE': 'en_US:en',
03:13:38 INFO - 'LOGNAME': 'cltbld',
03:13:38 INFO - 'MAIL': '/var/mail/cltbld',
03:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:38 INFO - 'MOZ_NO_REMOTE': '1',
03:13:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:38 INFO - 'NO_EM_RESTART': '1',
03:13:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:38 INFO - 'PWD': '/builds/slave/test',
03:13:38 INFO - 'SHELL': '/bin/bash',
03:13:38 INFO - 'SHLVL': '1',
03:13:38 INFO - 'TERM': 'linux',
03:13:38 INFO - 'TMOUT': '86400',
03:13:38 INFO - 'USER': 'cltbld',
03:13:38 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:38 INFO - '_': '/tools/buildbot/bin/python'}
03:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:39 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:13:39 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:13:39 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:13:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:13:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:13:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:13:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:13:40 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:13:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:13:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:13:41 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:13:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:13:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:13:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:13:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:13:42 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:13:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:13:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:13:42 INFO - Running setup.py install for manifestparser: started
03:13:42 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:13:42 INFO - Running setup.py install for mozcrash: started
03:13:43 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:13:43 INFO - Running setup.py install for mozdebug: started
03:13:43 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:13:43 INFO - Running setup.py install for mozdevice: started
03:13:43 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:13:43 INFO - Running setup.py install for mozfile: started
03:13:43 INFO - Running setup.py install for mozfile: finished with status 'done'
03:13:43 INFO - Running setup.py install for mozhttpd: started
03:13:44 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:13:44 INFO - Running setup.py install for mozinfo: started
03:13:44 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:13:44 INFO - Running setup.py install for mozInstall: started
03:13:44 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:13:44 INFO - Running setup.py install for mozleak: started
03:13:44 INFO - Running setup.py install for mozleak: finished with status 'done'
03:13:44 INFO - Running setup.py install for mozlog: started
03:13:44 INFO - Running setup.py install for mozlog: finished with status 'done'
03:13:44 INFO - Running setup.py install for moznetwork: started
03:13:45 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:13:45 INFO - Running setup.py install for mozprocess: started
03:13:45 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:13:45 INFO - Running setup.py install for mozprofile: started
03:13:45 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:13:45 INFO - Running setup.py install for mozrunner: started
03:13:45 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:13:45 INFO - Running setup.py install for mozscreenshot: started
03:13:46 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:13:46 INFO - Running setup.py install for moztest: started
03:13:46 INFO - Running setup.py install for moztest: finished with status 'done'
03:13:46 INFO - Running setup.py install for mozversion: started
03:13:46 INFO - Running setup.py install for mozversion: finished with status 'done'
03:13:46 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.14 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
03:13:46 INFO - Return code: 0
03:13:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:13: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')]}
03:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13: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')]}
03:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:46 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 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:46 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
03:13:46 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
03:13:46 INFO - Using env: {'DISPLAY': ':0',
03:13:46 INFO - 'HOME': '/home/cltbld',
03:13:46 INFO - 'LANG': 'en_US.UTF-8',
03:13:46 INFO - 'LANGUAGE': 'en_US:en',
03:13:46 INFO - 'LOGNAME': 'cltbld',
03:13:46 INFO - 'MAIL': '/var/mail/cltbld',
03:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:46 INFO - 'MOZ_NO_REMOTE': '1',
03:13:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:46 INFO - 'NO_EM_RESTART': '1',
03:13:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:46 INFO - 'PWD': '/builds/slave/test',
03:13:46 INFO - 'SHELL': '/bin/bash',
03:13:46 INFO - 'SHLVL': '1',
03:13:46 INFO - 'TERM': 'linux',
03:13:46 INFO - 'TMOUT': '86400',
03:13:46 INFO - 'USER': 'cltbld',
03:13:46 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:46 INFO - '_': '/tools/buildbot/bin/python'}
03:13:47 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:47 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:13:47 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))
03:13:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:13:47 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))
03:13:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:13:47 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))
03:13:47 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:13:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:13:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:13:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
03:13:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:13:48 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))
03:13:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:13:48 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))
03:13:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:13:48 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))
03:13:48 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
03:13:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:13:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:13:49 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:13:49 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))
03:13:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:13:49 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))
03:13:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:13:49 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))
03:13:49 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:13:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.14 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
03:13:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:13:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
03:13:50 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:13:50 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))
03:13:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:13:50 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))
03:13:50 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:13:51 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
03:13:51 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))
03:13:51 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))
03:13:51 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))
03:13:51 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))
03:13:51 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))
03:13:51 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))
03:13:51 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:13:52 INFO - Installing collected packages: six, blessings
03:13:52 INFO - Successfully installed blessings-1.6 six-1.10.0
03:13:53 INFO - Return code: 0
03:13:53 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:13:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:13:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:13:53 INFO - Reading from file tmpfile_stdout
03:13:53 INFO - Reading from file tmpfile_stderr
03:13:53 INFO - Current package versions:
03:13:53 INFO - blessings == 1.6
03:13:53 INFO - blobuploader == 1.2.4
03:13:53 INFO - docopt == 0.6.1
03:13:53 INFO - functools32 == 3.2.3.post2
03:13:53 INFO - jsonschema == 2.5.1
03:13:53 INFO - manifestparser == 1.2
03:13:53 INFO - mozInstall == 1.14
03:13:53 INFO - mozcrash == 1.0
03:13:53 INFO - mozdebug == 0.1
03:13:53 INFO - mozdevice == 0.51
03:13:53 INFO - mozfile == 1.2
03:13:53 INFO - mozhttpd == 0.7
03:13:53 INFO - mozinfo == 0.10
03:13:53 INFO - mozleak == 0.1
03:13:53 INFO - mozlog == 3.5
03:13:53 INFO - moznetwork == 0.27
03:13:53 INFO - mozprocess == 0.25
03:13:53 INFO - mozprofile == 0.29
03:13:53 INFO - mozrunner == 6.14
03:13:53 INFO - mozscreenshot == 0.1
03:13:53 INFO - mozsystemmonitor == 0.3
03:13:53 INFO - moztest == 0.8
03:13:53 INFO - mozversion == 1.4
03:13:53 INFO - psutil == 3.1.1
03:13:53 INFO - requests == 1.2.3
03:13:53 INFO - six == 1.10.0
03:13:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:13: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')]}
03:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:13: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')]}
03:13:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:13:53 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 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:13:53 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
03:13:53 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
03:13:53 INFO - Using env: {'DISPLAY': ':0',
03:13:53 INFO - 'HOME': '/home/cltbld',
03:13:53 INFO - 'LANG': 'en_US.UTF-8',
03:13:53 INFO - 'LANGUAGE': 'en_US:en',
03:13:53 INFO - 'LOGNAME': 'cltbld',
03:13:53 INFO - 'MAIL': '/var/mail/cltbld',
03:13:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:13:53 INFO - 'MOZ_NO_REMOTE': '1',
03:13:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:13:53 INFO - 'NO_EM_RESTART': '1',
03:13:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:13:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:13:53 INFO - 'PWD': '/builds/slave/test',
03:13:53 INFO - 'SHELL': '/bin/bash',
03:13:53 INFO - 'SHLVL': '1',
03:13:53 INFO - 'TERM': 'linux',
03:13:53 INFO - 'TMOUT': '86400',
03:13:53 INFO - 'USER': 'cltbld',
03:13:53 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:13:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:13:53 INFO - '_': '/tools/buildbot/bin/python'}
03:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
03:13:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
03:13:54 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:13:55 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:13:56 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
03:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:13:56 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
03:13:56 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:13:57 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
03:13:57 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:13:58 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
03:13:58 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:13:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
03:13:59 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:14:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
03:14:00 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
03:14:00 INFO - Found existing installation: requests 1.2.3
03:14:00 INFO - Uninstalling requests-1.2.3:
03:14:00 INFO - Successfully uninstalled requests-1.2.3
03:14:00 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
03:14:00 INFO - Return code: 0
03:14:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:14:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:14:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c38e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c9d990>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dc49a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, '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': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907', '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
03:14:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
03:14:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
03:14:00 INFO - Using env: {'DISPLAY': ':0',
03:14:00 INFO - 'HOME': '/home/cltbld',
03:14:00 INFO - 'LANG': 'en_US.UTF-8',
03:14:00 INFO - 'LANGUAGE': 'en_US:en',
03:14:00 INFO - 'LOGNAME': 'cltbld',
03:14:00 INFO - 'MAIL': '/var/mail/cltbld',
03:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:00 INFO - 'MOZ_NO_REMOTE': '1',
03:14:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:00 INFO - 'NO_EM_RESTART': '1',
03:14:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:00 INFO - 'PWD': '/builds/slave/test',
03:14:00 INFO - 'SHELL': '/bin/bash',
03:14:00 INFO - 'SHLVL': '1',
03:14:00 INFO - 'TERM': 'linux',
03:14:00 INFO - 'TMOUT': '86400',
03:14:00 INFO - 'USER': 'cltbld',
03:14:00 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:00 INFO - '_': '/tools/buildbot/bin/python'}
03:14:01 INFO - Ignoring indexes: https://pypi.python.org/simple
03:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:14:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:14:01 INFO - Return code: 0
03:14:01 INFO - Running post-action listener: _resource_record_post_action
03:14:01 INFO - Running post-action listener: _start_resource_monitoring
03:14:01 INFO - Starting resource monitoring.
03:14:01 INFO - [mozharness: 2017-11-30 11:14:01.452155Z] Finished create-virtualenv step (success)
03:14:01 INFO - [mozharness: 2017-11-30 11:14:01.452654Z] Running install step.
03:14:01 INFO - Running pre-action listener: _resource_record_pre_action
03:14:01 INFO - Running main action method: install
03:14:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:14:01 INFO - Reading from file tmpfile_stdout
03:14:01 INFO - Reading from file tmpfile_stderr
03:14:01 INFO - Detecting whether we're running mozinstall >=1.0...
03:14:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:14:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:14:02 INFO - Reading from file tmpfile_stdout
03:14:02 INFO - Output received:
03:14:02 INFO - Usage: mozinstall [options] installer
03:14:02 INFO - Options:
03:14:02 INFO - -h, --help show this help message and exit
03:14:02 INFO - -d DEST, --destination=DEST
03:14:02 INFO - Directory to install application into. [default:
03:14:02 INFO - "/builds/slave/test"]
03:14:02 INFO - --app=APP Application being installed. [default: firefox]
03:14:02 INFO - mkdir: /builds/slave/test/build/application
03:14:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
03:14:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:14:20 INFO - Reading from file tmpfile_stdout
03:14:20 INFO - Output received:
03:14:20 INFO - /builds/slave/test/build/application/firefox/firefox
03:14:20 INFO - Running post-action listener: _resource_record_post_action
03:14:20 INFO - [mozharness: 2017-11-30 11:14:20.493021Z] Finished install step (success)
03:14:20 INFO - [mozharness: 2017-11-30 11:14:20.493261Z] Running setup-mitmproxy step.
03:14:20 INFO - Running pre-action listener: _resource_record_pre_action
03:14:20 INFO - Running main action method: setup_mitmproxy
03:14:20 INFO - Skipping: mitmproxy is not required
03:14:20 INFO - Running post-action listener: _resource_record_post_action
03:14:20 INFO - [mozharness: 2017-11-30 11:14:20.494527Z] Finished setup-mitmproxy step (success)
03:14:20 INFO - [mozharness: 2017-11-30 11:14:20.494744Z] Running run-tests step.
03:14:20 INFO - Running pre-action listener: _resource_record_pre_action
03:14:20 INFO - Running pre-action listener: _set_gcov_prefix
03:14:20 INFO - Running main action method: run_tests
03:14:20 WARNING - Try message not found.
03:14:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:14:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:14:20 INFO - Python 2.7.3
03:14:20 INFO - Return code: 0
03:14:20 INFO - grabbing minidump binary from tooltool
03:14: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')]}
03:14:20 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc1b40>, '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 0x1dc2a40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dc2ed0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:14:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
03:14:20 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
03:14:20 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
03:14:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:14:20 INFO - Return code: 0
03:14:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:14:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:14:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:14:20 INFO - ENV: RUST_BACKTRACE is now full
03:14:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:14:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:14:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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
03:14:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-030 --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
03:14:20 INFO - Using env: {'DISPLAY': ':0',
03:14:20 INFO - 'HOME': '/home/cltbld',
03:14:20 INFO - 'LANG': 'en_US.UTF-8',
03:14:20 INFO - 'LANGUAGE': 'en_US:en',
03:14:20 INFO - 'LOGNAME': 'cltbld',
03:14:20 INFO - 'MAIL': '/var/mail/cltbld',
03:14:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:14:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:14:20 INFO - 'MOZ_ACCELERATED': '1',
03:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:20 INFO - 'MOZ_NO_REMOTE': '1',
03:14:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:14:20 INFO - 'MOZ_WEBRENDER': '1',
03:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:20 INFO - 'NO_EM_RESTART': '1',
03:14:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:20 INFO - 'PWD': '/builds/slave/test',
03:14:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:14:20 INFO - 'RUST_BACKTRACE': 'full',
03:14:20 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:14:20 INFO - 'SHELL': '/bin/bash',
03:14:20 INFO - 'SHLVL': '1',
03:14:20 INFO - 'TERM': 'linux',
03:14:20 INFO - 'TMOUT': '86400',
03:14:20 INFO - 'USER': 'cltbld',
03:14:20 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:20 INFO - '_': '/tools/buildbot/bin/python'}
03:14:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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
03:14:20 INFO - mozversion application_buildid: 20171130102254
03:14:20 INFO - mozversion application_changeset: ba283baf4e98aa3a5f45a17981077b98697aa73a
03:14:20 INFO - mozversion application_display_name: Nightly
03:14:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:14:20 INFO - mozversion application_name: Firefox
03:14:20 INFO - mozversion application_remotingname: firefox
03:14:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:14:20 INFO - mozversion application_vendor: Mozilla
03:14:20 INFO - mozversion application_version: 59.0a1
03:14:20 INFO - mozversion platform_buildid: 20171130102254
03:14:20 INFO - mozversion platform_changeset: ba283baf4e98aa3a5f45a17981077b98697aa73a
03:14:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:14:20 INFO - mozversion platform_version: 59.0a1
03:14:20 INFO - using testdate: 1512040460
03:14:20 INFO - actual date: 1512040460
03:14:20 INFO - starting webserver on 'localhost:50208'
03:14:20 INFO - SUITE-START | Running 3 tests
03:14:20 INFO - TEST-START | basic_compositor_video
03:14:20 INFO - Initialising browser for basic_compositor_video test...
03:14:20 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:14:20 INFO - Installing Add-ons
03:14:21 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50208/getInfo.html -profile /tmp/tmprN_Ed9/profile
03:14:21 INFO - TEST-INFO | started process 3757 (/builds/slave/test/build/application/firefox/firefox http://localhost:50208/getInfo.html)
03:14:38 INFO - TEST-INFO | 3757: exit 0
03:14:38 INFO - Browser initialized.
03:14:38 INFO - Running cycle 1/1 for basic_compositor_video test...
03:14:38 INFO - Using env: {'DISPLAY': ':0',
03:14:38 INFO - 'HOME': '/home/cltbld',
03:14:38 INFO - 'JSGC_DISABLE_POISONING': '1',
03:14:38 INFO - 'LANG': 'en_US.UTF-8',
03:14:38 INFO - 'LANGUAGE': 'en_US:en',
03:14:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:14:38 INFO - 'LOGNAME': 'cltbld',
03:14:38 INFO - 'MAIL': '/var/mail/cltbld',
03:14:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:14:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:14:38 INFO - 'MOZ_ACCELERATED': '1',
03:14:38 INFO - 'MOZ_CRASHREPORTER': '1',
03:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:14:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:14:38 INFO - 'MOZ_NO_REMOTE': '1',
03:14:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:14:38 INFO - 'MOZ_WEBRENDER': '1',
03:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:14:38 INFO - 'NO_EM_RESTART': '1',
03:14:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:14:38 INFO - 'PWD': '/builds/slave/test',
03:14:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:14:38 INFO - 'RUST_BACKTRACE': 'full',
03:14:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:14:38 INFO - 'SHELL': '/bin/bash',
03:14:38 INFO - 'SHLVL': '1',
03:14:38 INFO - 'TERM': 'linux',
03:14:38 INFO - 'TMOUT': '86400',
03:14:38 INFO - 'USER': 'cltbld',
03:14:38 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:14:38 INFO - '_': '/tools/buildbot/bin/python'}
03:14:38 INFO - TEST-INFO | started process 4053 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprN_Ed9/profile)
03:14:38 INFO - PID 4053 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:14:39 INFO - PID 4053 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:14:39 INFO - PID 4053 |
03:14:39 INFO - PID 4053 | (/builds/slave/test/build/application/firefox/firefox:4115): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:14:39 INFO - PID 4053 |
03:14:40 INFO - PID 4053 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:15:25 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.513006700167504 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4454706601466993 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4901452282157677 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.472669962917182 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0678732106339477 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.045563165905631 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 3.8563496143958864 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.115462962962966 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.2327856135402 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.19343815513627 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.65186821705426 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.642111368909513 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.994662004662011 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.648039867109627 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.898264367816098 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.712399328859054 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.881834862385328 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.689983277591974 ms/frame
03:15:25 INFO - PID 4053 |
03:15:25 INFO - PID 4053 | Cycle 1(1): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:16:11 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4595245901639347 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.448365973072215 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5000916666666666 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4788847583643134 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0614897959183676 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0488490853658536 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.0220576407506705 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5096140350877185 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.144343922651935 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.091267893660533 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.6816536661466435 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.662960372960373 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.928729792147805 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.645946843853822 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.931062355658185 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.692173913043475 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.898942528735634 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.712734899328848 ms/frame
03:16:11 INFO - PID 4053 |
03:16:11 INFO - PID 4053 | Cycle 1(2): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:16:57 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4550818330605564 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4308383961117865 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.48160876757651 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.478866171003717 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0523499491352992 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0489710365853657 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.03309811827957 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.082142857142857 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.167173611111114 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.1496161825726094 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.666928460342145 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.608951612903225 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.8813761467889885 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.712466442953028 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.912741935483865 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.668566666666666 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.944861111111112 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.66743333333332 ms/frame
03:16:57 INFO - PID 4053 |
03:16:57 INFO - PID 4053 | Cycle 1(3): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:17:43 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4580548730548735 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4371315468940313 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4879975124378113 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4816811414392057 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.051841302136318 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0501067073170716 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.208204768583449 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.167395833333338 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.3867616959064355 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.397439560439558 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.643931888544891 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.640846867749415 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.9297459584295655 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.737003367003365 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.920230414746538 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.6890301003344375 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.913778801843322 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.64714285714286 ms/frame
03:17:43 INFO - PID 4053 |
03:17:43 INFO - PID 4053 | Cycle 1(4): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:18:29 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.459143442622951 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.437423873325214 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.494318370739817 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.479349442379182 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0616581632653053 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0487576219512205 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 3.6551400730816086 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 3.691254612546125 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.4385207100591755 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.407367841409691 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.688601562499997 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.695645539906106 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.897206896551715 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.666816666666685 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.897908045977004 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.756908783783802 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.850000000000007 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.668050000000003 ms/frame
03:18:29 INFO - PID 4053 |
03:18:29 INFO - PID 4053 | Cycle 1(5): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:19:15 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.464039408866995 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4429792429792427 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.494152119700748 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4786555142503106 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0551934826883898 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0494664634146353 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 3.731567164179105 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5714107142857108 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.201792717086832 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.192945492662473 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.6452476780185785 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673808411214952 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.89893103448275 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.647126245847188 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.867151029748283 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.714781879194622 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.944780092592602 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.669566666666651 ms/frame
03:19:15 INFO - PID 4053 |
03:19:15 INFO - PID 4053 | Cycle 1(6): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:20:01 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4515277777777778 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4369732034104747 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4876699834162523 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.475352722772277 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.058460754332314 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.044703196347033 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.349195652173916 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.282965738758031 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.255212765957446 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.202468487394966 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.702946708463955 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.6640326340326315 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.897931034482764 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.68938127090301 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.896919540229876 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.6454817275747535 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.900574712643678 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.647607973421928 ms/frame
03:20:01 INFO - PID 4053 |
03:20:01 INFO - PID 4053 | Cycle 1(7): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:20:47 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4472960848287113 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.443192918192919 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.491677740863788 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4792565055762084 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.058414882772681 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0500381097560987 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.3415050651230125 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.168562499999999 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.413110294117648 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.415011037527594 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.637743431221018 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.674474299065419 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.962819025522047 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.690551839464878 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.865583524027465 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.712718120805367 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.912523041474662 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.647774086378724 ms/frame
03:20:47 INFO - PID 4053 |
03:20:47 INFO - PID 4053 | Cycle 1(8): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:21:33 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4618129614438065 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4363580998781975 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.492055647840532 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.481997518610423 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0611938775510215 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.040927051671733 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 3.6064122596153867 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5852419354838685 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.2193811533052035 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.212052631578952 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.688164062500005 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.620369515011545 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.913398617511523 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.670083333333338 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9134907834101424 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.713825503355709 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.897022988505751 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.712130872483209 ms/frame
03:21:33 INFO - PID 4053 |
03:21:33 INFO - PID 4053 | Cycle 1(9): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:22:19 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4516707516339866 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4368940316686967 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.48997510373444 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.479429987608426 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.055096741344197 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0538320610687024 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.060744248985114 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.186108786610877 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.267795163584635 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.238824152542377 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.608940092165901 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.609285714285715 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.881318807339452 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.690217391304348 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.881272935779818 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.668416666666672 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.882844036697244 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.689581939799323 ms/frame
03:22:19 INFO - PID 4053 |
03:22:19 INFO - PID 4053 | Cycle 1(10): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:23:04 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.441818551668023 ms/frame
03:23:04 INFO - PID 4053 |
03:23:04 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.443369963369964 ms/frame
03:23:04 INFO - PID 4053 |
03:23:04 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5022810675562965 ms/frame
03:23:04 INFO - PID 4053 |
03:23:04 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4752475247524752 ms/frame
03:23:04 INFO - PID 4053 |
03:23:04 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0615663265306114 ms/frame
03:23:04 INFO - PID 4053 |
03:23:04 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.049092987804878 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.184365411436542 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.176283924843421 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.3551959361393315 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.274957264957266 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.702766457680253 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.640603248259858 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.898103448275861 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.666983333333337 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.944664351851855 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.645016611295686 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.912718894009206 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.712768456375859 ms/frame
03:23:05 INFO - PID 4053 |
03:23:05 INFO - PID 4053 | Cycle 1(11): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:23:50 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4635180623973727 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.433509732360097 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.4917691029900326 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4732941903584678 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_startup = 3.061693877551021 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0533511450381674 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_startup = 4.196104895104895 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1_inclip = 4.176628392484341 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.214051966292133 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.124824742268043 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_startup = 4.666765163297044 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.480p.60fps.webm_scale_2_inclip = 4.620981524249427 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.865091533180783 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.711476510067103 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.912453917050685 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.689581939799347 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.930681293302542 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.6816166666666685 ms/frame
03:23:50 INFO - PID 4053 |
03:23:50 INFO - PID 4053 | Cycle 1(12): loaded http://localhost:50208/tests/video/video_playback.html (next: http://localhost:50208/tests/video/video_playback.html)
03:23:51 INFO - PID 4053 | __start_tp_report
03:23:51 INFO - PID 4053 | _x_x_mozilla_page_load
03:23:51 INFO - PID 4053 | _x_x_mozilla_page_load_details
03:23:51 INFO - PID 4053 | |i|pagename|runs|
03:23:51 INFO - PID 4053 | |0;240p.120fps.mp4_scale_1_startup;2.513006700167504;2.4595245901639347;2.4550818330605564;2.4580548730548735;2.459143442622951;2.464039408866995;2.4515277777777778;2.4472960848287113;2.4618129614438065;2.4516707516339866;2.441818551668023;2.4635180623973727
03:23:51 INFO - PID 4053 | |1;240p.120fps.mp4_scale_1_inclip;2.4454706601466993;2.448365973072215;2.4308383961117865;2.4371315468940313;2.437423873325214;2.4429792429792427;2.4369732034104747;2.443192918192919;2.4363580998781975;2.4368940316686967;2.443369963369964;2.433509732360097
03:23:51 INFO - PID 4053 | |2;240p.120fps.mp4_scale_1.1_startup;2.4901452282157677;2.5000916666666666;2.48160876757651;2.4879975124378113;2.494318370739817;2.494152119700748;2.4876699834162523;2.491677740863788;2.492055647840532;2.48997510373444;2.5022810675562965;2.4917691029900326
03:23:51 INFO - PID 4053 | |3;240p.120fps.mp4_scale_1.1_inclip;2.472669962917182;2.4788847583643134;2.478866171003717;2.4816811414392057;2.479349442379182;2.4786555142503106;2.475352722772277;2.4792565055762084;2.481997518610423;2.479429987608426;2.4752475247524752;2.4732941903584678
03:23:51 INFO - PID 4053 | |4;240p.120fps.mp4_scale_2_startup;3.0678732106339477;3.0614897959183676;3.0523499491352992;3.051841302136318;3.0616581632653053;3.0551934826883898;3.058460754332314;3.058414882772681;3.0611938775510215;3.055096741344197;3.0615663265306114;3.061693877551021
03:23:51 INFO - PID 4053 | |5;240p.120fps.mp4_scale_2_inclip;3.045563165905631;3.0488490853658536;3.0489710365853657;3.0501067073170716;3.0487576219512205;3.0494664634146353;3.044703196347033;3.0500381097560987;3.040927051671733;3.0538320610687024;3.049092987804878;3.0533511450381674
03:23:51 INFO - PID 4053 | |6;480p.60fps.webm_scale_1_startup;3.8563496143958864;4.0220576407506705;4.03309811827957;4.208204768583449;3.6551400730816086;3.731567164179105;4.349195652173916;4.3415050651230125;3.6064122596153867;4.060744248985114;4.184365411436542;4.196104895104895
03:23:51 INFO - PID 4053 | |7;480p.60fps.webm_scale_1_inclip;4.115462962962966;3.5096140350877185;4.082142857142857;4.167395833333338;3.691254612546125;3.5714107142857108;4.282965738758031;4.168562499999999;3.5852419354838685;4.186108786610877;4.176283924843421;4.176628392484341
03:23:51 INFO - PID 4053 | |8;480p.60fps.webm_scale_1.1_startup;4.2327856135402;4.144343922651935;4.167173611111114;4.3867616959064355;4.4385207100591755;4.201792717086832;4.255212765957446;4.413110294117648;4.2193811533052035;4.267795163584635;4.3551959361393315;4.214051966292133
03:23:51 INFO - PID 4053 | |9;480p.60fps.webm_scale_1.1_inclip;4.19343815513627;4.091267893660533;4.1496161825726094;4.397439560439558;4.407367841409691;4.192945492662473;4.202468487394966;4.415011037527594;4.212052631578952;4.238824152542377;4.274957264957266;4.124824742268043
03:23:51 INFO - PID 4053 | |10;480p.60fps.webm_scale_2_startup;4.65186821705426;4.6816536661466435;4.666928460342145;4.643931888544891;4.688601562499997;4.6452476780185785;4.702946708463955;4.637743431221018;4.688164062500005;4.608940092165901;4.702766457680253;4.666765163297044
03:23:51 INFO - PID 4053 | |11;480p.60fps.webm_scale_2_inclip;4.642111368909513;4.662960372960373;4.608951612903225;4.640846867749415;4.695645539906106;4.673808411214952;4.6640326340326315;4.674474299065419;4.620369515011545;4.609285714285715;4.640603248259858;4.620981524249427
03:23:51 INFO - PID 4053 | |12;1080p.60fps.mp4_scale_1_startup;6.994662004662011;6.928729792147805;6.8813761467889885;6.9297459584295655;6.897206896551715;6.89893103448275;6.897931034482764;6.962819025522047;6.913398617511523;6.881318807339452;6.898103448275861;6.865091533180783
03:23:51 INFO - PID 4053 | |13;1080p.60fps.mp4_scale_1_inclip;6.648039867109627;6.645946843853822;6.712466442953028;6.737003367003365;6.666816666666685;6.647126245847188;6.68938127090301;6.690551839464878;6.670083333333338;6.690217391304348;6.666983333333337;6.711476510067103
03:23:51 INFO - PID 4053 | |14;1080p.60fps.mp4_scale_1.1_startup;6.898264367816098;6.931062355658185;6.912741935483865;6.920230414746538;6.897908045977004;6.867151029748283;6.896919540229876;6.865583524027465;6.9134907834101424;6.881272935779818;6.944664351851855;6.912453917050685
03:23:51 INFO - PID 4053 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.712399328859054;6.692173913043475;6.668566666666666;6.6890301003344375;6.756908783783802;6.714781879194622;6.6454817275747535;6.712718120805367;6.713825503355709;6.668416666666672;6.645016611295686;6.689581939799347
03:23:51 INFO - PID 4053 | |16;1080p.60fps.mp4_scale_2_startup;6.881834862385328;6.898942528735634;6.944861111111112;6.913778801843322;6.850000000000007;6.944780092592602;6.900574712643678;6.912523041474662;6.897022988505751;6.882844036697244;6.912718894009206;6.930681293302542
03:23:51 INFO - PID 4053 | |17;1080p.60fps.mp4_scale_2_inclip;6.689983277591974;6.712734899328848;6.66743333333332;6.64714285714286;6.668050000000003;6.669566666666651;6.647607973421928;6.647774086378724;6.712130872483209;6.689581939799323;6.712768456375859;6.6816166666666685
03:23:51 INFO - PID 4053 | __end_tp_report
03:23:51 INFO - PID 4053 | __start_cc_report
03:23:51 INFO - PID 4053 | _x_x_mozilla_cycle_collect,567
03:23:51 INFO - PID 4053 | __end_cc_report
03:23:51 INFO - PID 4053 | __startTimestamp1512041031210__endTimestamp
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | ------- Summary: start -------
03:23:51 INFO - PID 4053 | Number of tests: 18
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.46 Median:2.46 stddev:0.02 (0.7%) stddev-sans-first:0.01
03:23:51 INFO - PID 4053 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.4 2.5 2.5 2.4 2.5
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.44 Median:2.44 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:23:51 INFO - PID 4053 | Values: 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.49 Median:2.49 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:23:51 INFO - PID 4053 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.48 Median:2.48 stddev:0.00 (0.1%) stddev-sans-first:0.00
03:23:51 INFO - PID 4053 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.06 Median:3.06 stddev:0.00 (0.2%) stddev-sans-first:0.00
03:23:51 INFO - PID 4053 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.05 Median:3.05 stddev:0.00 (0.1%) stddev-sans-first:0.00
03:23:51 INFO - PID 4053 | Values: 3.0 3.0 3.0 3.1 3.0 3.0 3.0 3.1 3.0 3.1 3.0 3.1
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.02 Median:4.12 stddev:0.26 (6.2%) stddev-sans-first:0.26
03:23:51 INFO - PID 4053 | Values: 3.9 4.0 4.0 4.2 3.7 3.7 4.3 4.3 3.6 4.1 4.2 4.2
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.98 Median:4.17 stddev:0.29 (7.0%) stddev-sans-first:0.30
03:23:51 INFO - PID 4053 | Values: 4.1 3.5 4.1 4.2 3.7 3.6 4.3 4.2 3.6 4.2 4.2 4.2
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.27 Median:4.26 stddev:0.10 (2.3%) stddev-sans-first:0.10
03:23:51 INFO - PID 4053 | Values: 4.2 4.1 4.2 4.4 4.4 4.2 4.3 4.4 4.2 4.3 4.4 4.2
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.24 Median:4.23 stddev:0.11 (2.6%) stddev-sans-first:0.11
03:23:51 INFO - PID 4053 | Values: 4.2 4.1 4.1 4.4 4.4 4.2 4.2 4.4 4.2 4.2 4.3 4.1
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.67 Median:4.67 stddev:0.03 (0.6%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 4.7 4.7 4.7 4.6 4.7 4.6 4.7 4.6 4.7 4.6 4.7 4.7
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.65 Median:4.65 stddev:0.03 (0.6%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 4.6 4.7 4.6 4.6 4.7 4.7 4.7 4.7 4.6 4.6 4.6 4.6
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:6.91 Median:6.91 stddev:0.04 (0.5%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 7.0 6.9 6.9 6.9 6.9 6.9 6.9 7.0 6.9 6.9 6.9 6.9
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.68 Median:6.69 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 6.6 6.6 6.7 6.7 6.7 6.6 6.7 6.7 6.7 6.7 6.7 6.7
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:6.90 Median:6.91 stddev:0.02 (0.3%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.69 Median:6.70 stddev:0.03 (0.5%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 6.7 6.7 6.7 6.7 6.8 6.7 6.6 6.7 6.7 6.7 6.6 6.7
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:6.91 Median:6.91 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9 6.9
03:23:51 INFO - PID 4053 |
03:23:51 INFO - PID 4053 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.68 Median:6.69 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:23:51 INFO - PID 4053 | Values: 6.7 6.7 6.7 6.6 6.7 6.7 6.6 6.6 6.7 6.7 6.7 6.7
03:23:51 INFO - PID 4053 | -------- Summary: end --------
03:23:51 INFO - PID 4053 |
03:23:56 INFO - TEST-INFO | 4053: exit 0
03:23:56 INFO - TEST-OK | basic_compositor_video | took 575951ms
03:23:56 INFO - TEST-START | glvideo
03:23:56 INFO - Initialising browser for glvideo test...
03:23:56 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:23:56 INFO - Installing Add-ons
03:23:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50208/getInfo.html -profile /tmp/tmp4IHL74/profile
03:23:56 INFO - TEST-INFO | started process 5362 (/builds/slave/test/build/application/firefox/firefox http://localhost:50208/getInfo.html)
03:24:08 INFO - TEST-INFO | 5362: exit 0
03:24:08 INFO - Browser initialized.
03:24:08 INFO - Running cycle 1/1 for glvideo test...
03:24:08 INFO - Using env: {'DISPLAY': ':0',
03:24:08 INFO - 'HOME': '/home/cltbld',
03:24:08 INFO - 'JSGC_DISABLE_POISONING': '1',
03:24:08 INFO - 'LANG': 'en_US.UTF-8',
03:24:08 INFO - 'LANGUAGE': 'en_US:en',
03:24:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:24:08 INFO - 'LOGNAME': 'cltbld',
03:24:08 INFO - 'MAIL': '/var/mail/cltbld',
03:24:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:24:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:24:08 INFO - 'MOZ_ACCELERATED': '1',
03:24:08 INFO - 'MOZ_CRASHREPORTER': '1',
03:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:24:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:24:08 INFO - 'MOZ_NO_REMOTE': '1',
03:24:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:24:08 INFO - 'MOZ_WEBRENDER': '1',
03:24:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:24:08 INFO - 'NO_EM_RESTART': '1',
03:24:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:24:08 INFO - 'PWD': '/builds/slave/test',
03:24:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:24:08 INFO - 'RUST_BACKTRACE': 'full',
03:24:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:24:08 INFO - 'SHELL': '/bin/bash',
03:24:08 INFO - 'SHLVL': '1',
03:24:08 INFO - 'TERM': 'linux',
03:24:08 INFO - 'TMOUT': '86400',
03:24:08 INFO - 'USER': 'cltbld',
03:24:08 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:24:08 INFO - '_': '/tools/buildbot/bin/python'}
03:24:08 INFO - TEST-INFO | started process 5704 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4IHL74/profile)
03:24:09 INFO - PID 5704 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:24:09 INFO - PID 5704 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:24:09 INFO - PID 5704 |
03:24:09 INFO - PID 5704 | (/builds/slave/test/build/application/firefox/firefox:5765): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:24:09 INFO - PID 5704 |
03:24:11 INFO - PID 5704 | [talos glvideo result] Mean tick time across 100 ticks: 6.86575 ms
03:24:11 INFO - PID 5704 | Cycle 1(1): loaded http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html)
03:24:11 INFO - PID 5704 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:24:12 INFO - PID 5704 | [talos glvideo result] Mean tick time across 100 ticks: 6.5799 ms
03:24:12 INFO - PID 5704 | Cycle 1(2): loaded http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html)
03:24:13 INFO - PID 5704 | [talos glvideo result] Mean tick time across 100 ticks: 6.454250000000001 ms
03:24:13 INFO - PID 5704 | Cycle 1(3): loaded http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html)
03:24:15 INFO - PID 5704 | [talos glvideo result] Mean tick time across 100 ticks: 6.687950000000001 ms
03:24:15 INFO - PID 5704 | Cycle 1(4): loaded http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html)
03:24:16 INFO - PID 5704 | [talos glvideo result] Mean tick time across 100 ticks: 6.6402 ms
03:24:16 INFO - PID 5704 | Cycle 1(5): loaded http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:50208/tests/webgl/benchmarks/video/video_upload.html)
03:24:16 INFO - PID 5704 | __start_tp_report
03:24:16 INFO - PID 5704 | _x_x_mozilla_page_load
03:24:16 INFO - PID 5704 | _x_x_mozilla_page_load_details
03:24:16 INFO - PID 5704 | |i|pagename|runs|
03:24:16 INFO - PID 5704 | |0;Mean tick time across 100 ticks: ;6.86575;6.5799;6.454250000000001;6.687950000000001;6.6402
03:24:16 INFO - PID 5704 | __end_tp_report
03:24:16 INFO - PID 5704 | __start_cc_report
03:24:16 INFO - PID 5704 | _x_x_mozilla_cycle_collect,276
03:24:16 INFO - PID 5704 | __end_cc_report
03:24:16 INFO - PID 5704 | __startTimestamp1512041056697__endTimestamp
03:24:16 INFO - PID 5704 |
03:24:16 INFO - PID 5704 | ------- Summary: start -------
03:24:16 INFO - PID 5704 | Number of tests: 1
03:24:16 INFO - PID 5704 |
03:24:16 INFO - PID 5704 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.65 Median:6.64 stddev:0.15 (2.3%) stddev-sans-first:0.10
03:24:16 INFO - PID 5704 | Values: 6.9 6.6 6.5 6.7 6.6
03:24:16 INFO - PID 5704 | -------- Summary: end --------
03:24:16 INFO - PID 5704 |
03:24:17 INFO - PID 5704 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:24:22 INFO - TEST-INFO | 5704: exit 0
03:24:22 INFO - TEST-OK | glvideo | took 25669ms
03:24:22 INFO - TEST-START | displaylist_mutate
03:24:22 INFO - Initialising browser for displaylist_mutate test...
03:24:22 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:24:22 INFO - Installing Add-ons
03:24:22 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:50208/getInfo.html -profile /tmp/tmpMzsBrf/profile
03:24:22 INFO - TEST-INFO | started process 5943 (/builds/slave/test/build/application/firefox/firefox http://localhost:50208/getInfo.html)
03:24:34 INFO - TEST-INFO | 5943: exit 0
03:24:34 INFO - Browser initialized.
03:24:34 INFO - Running cycle 1/1 for displaylist_mutate test...
03:24:34 INFO - Using env: {'DISPLAY': ':0',
03:24:34 INFO - 'HOME': '/home/cltbld',
03:24:34 INFO - 'JSGC_DISABLE_POISONING': '1',
03:24:34 INFO - 'LANG': 'en_US.UTF-8',
03:24:34 INFO - 'LANGUAGE': 'en_US:en',
03:24:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:24:34 INFO - 'LOGNAME': 'cltbld',
03:24:34 INFO - 'MAIL': '/var/mail/cltbld',
03:24:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:24:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:24:34 INFO - 'MOZ_ACCELERATED': '1',
03:24:34 INFO - 'MOZ_CRASHREPORTER': '1',
03:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:24:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:24:34 INFO - 'MOZ_NO_REMOTE': '1',
03:24:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:24:34 INFO - 'MOZ_WEBRENDER': '1',
03:24:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:24:34 INFO - 'NO_EM_RESTART': '1',
03:24:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:24:34 INFO - 'PWD': '/builds/slave/test',
03:24:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:24:34 INFO - 'RUST_BACKTRACE': 'full',
03:24:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:24:34 INFO - 'SHELL': '/bin/bash',
03:24:34 INFO - 'SHLVL': '1',
03:24:34 INFO - 'TERM': 'linux',
03:24:34 INFO - 'TMOUT': '86400',
03:24:34 INFO - 'USER': 'cltbld',
03:24:34 INFO - 'XDG_SESSION_COOKIE': '3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907',
03:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:24:34 INFO - '_': '/tools/buildbot/bin/python'}
03:24:34 INFO - TEST-INFO | started process 6237 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMzsBrf/profile)
03:24:34 INFO - PID 6237 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:24:35 INFO - PID 6237 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:24:35 INFO - PID 6237 |
03:24:35 INFO - PID 6237 | (/builds/slave/test/build/application/firefox/firefox:6298): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:24:35 INFO - PID 6237 |
03:24:41 INFO - PID 6237 | Cycle 1(1): loaded http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html)
03:24:41 INFO - PID 6237 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:24:47 INFO - PID 6237 | Cycle 1(2): loaded http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html)
03:24:54 INFO - PID 6237 | Cycle 1(3): loaded http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html)
03:25:00 INFO - PID 6237 | Cycle 1(4): loaded http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html)
03:25:06 INFO - PID 6237 | Cycle 1(5): loaded http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:50208/tests/layout/benchmarks/displaylist_mutate.html)
03:25:07 INFO - PID 6237 | __start_tp_report
03:25:07 INFO - PID 6237 | _x_x_mozilla_page_load
03:25:07 INFO - PID 6237 | _x_x_mozilla_page_load_details
03:25:07 INFO - PID 6237 | |i|pagename|runs|
03:25:07 INFO - PID 6237 | |0;/displaylist_mutate.html;5587.68;5471.34;5459.424999999999;5598.955;5652.65
03:25:07 INFO - PID 6237 | __end_tp_report
03:25:07 INFO - PID 6237 | __start_cc_report
03:25:07 INFO - PID 6237 | _x_x_mozilla_cycle_collect,246
03:25:07 INFO - PID 6237 | __end_cc_report
03:25:07 INFO - PID 6237 | __startTimestamp1512041107183__endTimestamp
03:25:07 INFO - PID 6237 |
03:25:07 INFO - PID 6237 | ------- Summary: start -------
03:25:07 INFO - PID 6237 | Number of tests: 1
03:25:07 INFO - PID 6237 |
03:25:07 INFO - PID 6237 | [#0] /displaylist_mutate.html Cycles:5 Average:5554.01 Median:5587.68 stddev:84.65 (1.5%) stddev-sans-first:95.30
03:25:07 INFO - PID 6237 | Values: 5587.7 5471.3 5459.4 5599.0 5652.6
03:25:07 INFO - PID 6237 | -------- Summary: end --------
03:25:07 INFO - PID 6237 |
03:25:07 INFO - PID 6237 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:25:13 INFO - TEST-INFO | 6237: exit 0
03:25:13 INFO - TEST-OK | displaylist_mutate | took 50488ms
03:25:13 INFO - SUITE-END | took 652s
03:25:13 INFO - Completed test suite (00:10:52)
03:25:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.333160591881714, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.513006700167504, 2.4595245901639347, 2.4550818330605564, 2.4580548730548735, 2.459143442622951, 2.464039408866995, 2.4515277777777778, 2.4472960848287113, 2.4618129614438065, 2.4516707516339866, 2.441818551668023, 2.4635180623973727], "value": 2.4580548730548735, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4454706601466993, 2.448365973072215, 2.4308383961117865, 2.4371315468940313, 2.437423873325214, 2.4429792429792427, 2.4369732034104747, 2.443192918192919, 2.4363580998781975, 2.4368940316686967, 2.443369963369964, 2.433509732360097], "value": 2.4371315468940313, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4901452282157677, 2.5000916666666666, 2.48160876757651, 2.4879975124378113, 2.494318370739817, 2.494152119700748, 2.4876699834162523, 2.491677740863788, 2.492055647840532, 2.48997510373444, 2.5022810675562965, 2.4917691029900326], "value": 2.4917691029900326, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.472669962917182, 2.4788847583643134, 2.478866171003717, 2.4816811414392057, 2.479349442379182, 2.4786555142503106, 2.475352722772277, 2.4792565055762084, 2.481997518610423, 2.479429987608426, 2.4752475247524752, 2.4732941903584678], "value": 2.4788847583643134, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0678732106339477, 3.0614897959183676, 3.0523499491352992, 3.051841302136318, 3.0616581632653053, 3.0551934826883898, 3.058460754332314, 3.058414882772681, 3.0611938775510215, 3.055096741344197, 3.0615663265306114, 3.061693877551021], "value": 3.058460754332314, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.045563165905631, 3.0488490853658536, 3.0489710365853657, 3.0501067073170716, 3.0487576219512205, 3.0494664634146353, 3.044703196347033, 3.0500381097560987, 3.040927051671733, 3.0538320610687024, 3.049092987804878, 3.0533511450381674], "value": 3.049092987804878, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.8563496143958864, 4.0220576407506705, 4.03309811827957, 4.208204768583449, 3.6551400730816086, 3.731567164179105, 4.349195652173916, 4.3415050651230125, 3.6064122596153867, 4.060744248985114, 4.184365411436542, 4.196104895104895], "value": 4.060744248985114, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.115462962962966, 3.5096140350877185, 4.082142857142857, 4.167395833333338, 3.691254612546125, 3.5714107142857108, 4.282965738758031, 4.168562499999999, 3.5852419354838685, 4.186108786610877, 4.176283924843421, 4.176628392484341], "value": 4.167395833333338, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.2327856135402, 4.144343922651935, 4.167173611111114, 4.3867616959064355, 4.4385207100591755, 4.201792717086832, 4.255212765957446, 4.413110294117648, 4.2193811533052035, 4.267795163584635, 4.3551959361393315, 4.214051966292133], "value": 4.255212765957446, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.19343815513627, 4.091267893660533, 4.1496161825726094, 4.397439560439558, 4.407367841409691, 4.192945492662473, 4.202468487394966, 4.415011037527594, 4.212052631578952, 4.238824152542377, 4.274957264957266, 4.124824742268043], "value": 4.212052631578952, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.65186821705426, 4.6816536661466435, 4.666928460342145, 4.643931888544891, 4.688601562499997, 4.6452476780185785, 4.702946708463955, 4.637743431221018, 4.688164062500005, 4.608940092165901, 4.702766457680253, 4.666765163297044], "value": 4.666928460342145, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.642111368909513, 4.662960372960373, 4.608951612903225, 4.640846867749415, 4.695645539906106, 4.673808411214952, 4.6640326340326315, 4.674474299065419, 4.620369515011545, 4.609285714285715, 4.640603248259858, 4.620981524249427], "value": 4.640846867749415, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.994662004662011, 6.928729792147805, 6.8813761467889885, 6.9297459584295655, 6.897206896551715, 6.89893103448275, 6.897931034482764, 6.962819025522047, 6.913398617511523, 6.881318807339452, 6.898103448275861, 6.865091533180783], "value": 6.898103448275861, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.648039867109627, 6.645946843853822, 6.712466442953028, 6.737003367003365, 6.666816666666685, 6.647126245847188, 6.68938127090301, 6.690551839464878, 6.670083333333338, 6.690217391304348, 6.666983333333337, 6.711476510067103], "value": 6.68938127090301, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.898264367816098, 6.931062355658185, 6.912741935483865, 6.920230414746538, 6.897908045977004, 6.867151029748283, 6.896919540229876, 6.865583524027465, 6.9134907834101424, 6.881272935779818, 6.944664351851855, 6.912453917050685], "value": 6.912453917050685, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.712399328859054, 6.692173913043475, 6.668566666666666, 6.6890301003344375, 6.756908783783802, 6.714781879194622, 6.6454817275747535, 6.712718120805367, 6.713825503355709, 6.668416666666672, 6.645016611295686, 6.689581939799347], "value": 6.689581939799347, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.881834862385328, 6.898942528735634, 6.944861111111112, 6.913778801843322, 6.850000000000007, 6.944780092592602, 6.900574712643678, 6.912523041474662, 6.897022988505751, 6.882844036697244, 6.912718894009206, 6.930681293302542], "value": 6.912523041474662, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.689983277591974, 6.712734899328848, 6.66743333333332, 6.64714285714286, 6.668050000000003, 6.669566666666651, 6.647607973421928, 6.647774086378724, 6.712130872483209, 6.689581939799323, 6.712768456375859, 6.6816166666666685], "value": 6.669566666666651, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.86575, 6.5799, 6.454250000000001, 6.687950000000001, 6.6402], "value": 6.61005, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5587.68, 5471.34, 5459.424999999999, 5598.955, 5652.65], "value": 5535.1475, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
03:25:13 INFO - Return code: 0
03:25:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:25:13 INFO - # TBPL SUCCESS #
03:25:13 INFO - Running post-action listener: _package_coverage_data
03:25:13 INFO - Running post-action listener: _resource_record_post_action
03:25:13 INFO - [mozharness: 2017-11-30 11:25:13.231503Z] Finished run-tests step (success)
03:25:13 INFO - Running post-run listener: _resource_record_post_run
03:25:13 INFO - Total resource usage - Wall time: 672s; CPU: 44.0%; Read bytes: 26198016; Write bytes: 356196352; Read time: 14448; Write time: 247944
03:25:13 INFO - TinderboxPrint: CPU usage
43.8%
03:25:13 INFO - TinderboxPrint: I/O read bytes / time
26,198,016 / 14,448
03:25:13 INFO - TinderboxPrint: I/O write bytes / time
356,196,352 / 247,944
03:25:13 INFO - TinderboxPrint: CPU idle
2,828.6 (56.0%)
03:25:13 INFO - TinderboxPrint: CPU system
664.8 (13.2%)
03:25:13 INFO - TinderboxPrint: CPU user
1,529.4 (30.3%)
03:25:13 INFO - TinderboxPrint: Swap in / out
0 / 0
03:25:13 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5304320; Read time: 0; Write time: 6392
03:25:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:25:13 INFO - run-tests - Wall time: 653s; CPU: 45.0%; Read bytes: 23801856; Write bytes: 349863936; Read time: 13272; Write time: 241424
03:25:13 INFO - Running post-run listener: _upload_blobber_files
03:25:13 INFO - Blob upload gear active.
03:25:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:25:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
03:25:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
03:25:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
03:25:13 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:13 INFO - SNIMissingWarning
03:25:13 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:13 INFO - InsecurePlatformWarning
03:25:13 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:25:13 INFO - SubjectAltNameWarning
03:25:14 INFO - (blobuploader) - INFO - Open directory for files ...
03:25:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:25:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:25:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:25:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:14 INFO - InsecurePlatformWarning
03:25:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:25:14 INFO - SubjectAltNameWarning
03:25:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:19 INFO - InsecurePlatformWarning
03:25:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:25:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:25:19 INFO - (blobuploader) - INFO - Done attempting.
03:25:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:25:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:25:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:25:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:19 INFO - InsecurePlatformWarning
03:25:19 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:25:19 INFO - SubjectAltNameWarning
03:25:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:20 INFO - InsecurePlatformWarning
03:25:20 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:25:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:25:20 INFO - (blobuploader) - INFO - Done attempting.
03:25:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:25:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:25:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:25:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:20 INFO - InsecurePlatformWarning
03:25:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:25:20 INFO - SubjectAltNameWarning
03:25:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:21 INFO - InsecurePlatformWarning
03:25:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:25:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:25:21 INFO - (blobuploader) - INFO - Done attempting.
03:25:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:25:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:25:21 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:25:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:25:21 INFO - InsecurePlatformWarning
03:25:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:25:21 INFO - SubjectAltNameWarning
03:25:22 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:25:22 INFO - InsecurePlatformWarning
03:25:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:25:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:25:22 INFO - (blobuploader) - INFO - Done attempting.
03:25:22 INFO - (blobuploader) - INFO - Iteration through files over.
03:25:22 INFO - Return code: 0
03:25:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:25:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:25:22 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b4ea294bcd5320ee9a964b3a53dae5125aeffdaab050c5fa0bb8970114376fb9791eb6febc626269044b3c506a4e5fe552d9a029c67dbe6d7658cdadb7b8083", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5299dfd383db2eca8b69208618803d0b8adcf78bb3b8b21a75945906be71534a398652bc84b16943864ee317b6114668a1e6d4d25f0bd532bd7d307b77f93dd2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86caccd92a6e4d774d6f1390e0809c881386640b1c6ad023619901167c0501dd52208a607d459768423e67976f68c7c8b52b47f4145a91d1c5fd5ff3d48f0001", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff99bf06f17e0077881f478c3198adde8036050b835f079d7a91968983e8d701a58a96be0814400cc68aee08714b0fb123fa2398f93819296a364701713208a"}
03:25:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:25:22 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:25:22 INFO - Contents:
03:25:22 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b4ea294bcd5320ee9a964b3a53dae5125aeffdaab050c5fa0bb8970114376fb9791eb6febc626269044b3c506a4e5fe552d9a029c67dbe6d7658cdadb7b8083", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5299dfd383db2eca8b69208618803d0b8adcf78bb3b8b21a75945906be71534a398652bc84b16943864ee317b6114668a1e6d4d25f0bd532bd7d307b77f93dd2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86caccd92a6e4d774d6f1390e0809c881386640b1c6ad023619901167c0501dd52208a607d459768423e67976f68c7c8b52b47f4145a91d1c5fd5ff3d48f0001", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff99bf06f17e0077881f478c3198adde8036050b835f079d7a91968983e8d701a58a96be0814400cc68aee08714b0fb123fa2398f93819296a364701713208a"}
03:25:22 INFO - Running post-run listener: copy_logs_to_upload_dir
03:25:22 INFO - Copying logs to upload dir...
03:25:22 INFO - mkdir: /builds/slave/test/build/upload/logs
03:25:22 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=737.565147
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 17 secs) (at 2017-11-30 03:25:22.378828) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-30 03:25:22.382740) =========
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=3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b4ea294bcd5320ee9a964b3a53dae5125aeffdaab050c5fa0bb8970114376fb9791eb6febc626269044b3c506a4e5fe552d9a029c67dbe6d7658cdadb7b8083", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5299dfd383db2eca8b69208618803d0b8adcf78bb3b8b21a75945906be71534a398652bc84b16943864ee317b6114668a1e6d4d25f0bd532bd7d307b77f93dd2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86caccd92a6e4d774d6f1390e0809c881386640b1c6ad023619901167c0501dd52208a607d459768423e67976f68c7c8b52b47f4145a91d1c5fd5ff3d48f0001", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff99bf06f17e0077881f478c3198adde8036050b835f079d7a91968983e8d701a58a96be0814400cc68aee08714b0fb123fa2398f93819296a364701713208a"}
build_url:https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011270
build_url: 'https://queue.taskcluster.net/v1/task/JPrtCsqNTYWzJADHdO5gVw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b4ea294bcd5320ee9a964b3a53dae5125aeffdaab050c5fa0bb8970114376fb9791eb6febc626269044b3c506a4e5fe552d9a029c67dbe6d7658cdadb7b8083", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5299dfd383db2eca8b69208618803d0b8adcf78bb3b8b21a75945906be71534a398652bc84b16943864ee317b6114668a1e6d4d25f0bd532bd7d307b77f93dd2", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/86caccd92a6e4d774d6f1390e0809c881386640b1c6ad023619901167c0501dd52208a607d459768423e67976f68c7c8b52b47f4145a91d1c5fd5ff3d48f0001", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ff99bf06f17e0077881f478c3198adde8036050b835f079d7a91968983e8d701a58a96be0814400cc68aee08714b0fb123fa2398f93819296a364701713208a"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-30 03:25:22.413586) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 03:25:22.414071) =========
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=3836458cccd887f2d2de3cf1000001de-1512040151.147618-1759392907
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004513
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-30 03:25:22.499467) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-30 03:25:22.499790) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-30 03:25:22.500096) =========
========= Total master_lag: 0.26 =========