builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-015
starttime: 1511901152.49
results: success (0)
revision: c2248f85346939d3e0b01f57276c440ccb2d16a1
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.493737) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.494176) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.494470) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.513347) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.513613) =========
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=20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-28 12:32:32-- 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% 29.9M=0s
2017-11-28 12:32:32 (29.9 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.170836
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.702351) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.702657) =========
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=20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.018981
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:32.752351) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-28 12:32:32.752678) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c2248f85346939d3e0b01f57276c440ccb2d16a1 --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 c2248f85346939d3e0b01f57276c440ccb2d16a1 --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=20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-28 12:32:32,794 truncating revision to first 12 chars
2017-11-28 12:32:32,794 Setting DEBUG logging.
2017-11-28 12:32:32,794 attempt 1/10
2017-11-28 12:32:32,794 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c2248f853469?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-28 12:32:33,977 unpacking tar archive at: mozilla-central-c2248f853469/testing/mozharness/
program finished with exit code 0
elapsedTime=1.783818
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-28 12:32:34.554118) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:34.554447) =========
script_repo_revision: c2248f85346939d3e0b01f57276c440ccb2d16a1
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:34.554827) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:34.555088) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-28 12:32:34.570043) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 15 secs) (at 2017-11-28 12:32:34.570380) =========
/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=20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
12:32:34 INFO - MultiFileLogger online at 20171128 12:32:34 in /builds/slave/test
12:32:34 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
12:32:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
12:32:34 INFO - {'append_to_log': False,
12:32:34 INFO - 'base_work_dir': '/builds/slave/test',
12:32:34 INFO - 'blob_upload_branch': 'Firefox',
12:32:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
12:32:34 INFO - 'branch': 'Firefox',
12:32:34 INFO - 'buildbot_json_path': 'buildprops.json',
12:32:34 INFO - 'code_coverage': False,
12:32:34 INFO - 'config_files': ('talos/linux_config.py',),
12:32:34 INFO - 'default_actions': ('clobber',
12:32:34 INFO - 'read-buildbot-config',
12:32:34 INFO - 'download-and-extract',
12:32:34 INFO - 'populate-webroot',
12:32:34 INFO - 'create-virtualenv',
12:32:34 INFO - 'install',
12:32:34 INFO - 'setup-mitmproxy',
12:32:34 INFO - 'run-tests'),
12:32:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:32:34 INFO - 'disable_ccov_upload': False,
12:32:34 INFO - 'disable_stylo': False,
12:32:34 INFO - 'download_minidump_stackwalk': True,
12:32:34 INFO - 'download_symbols': 'ondemand',
12:32:34 INFO - 'e10s': False,
12:32:34 INFO - 'enable_stylo': False,
12:32:34 INFO - 'enable_webrender': False,
12:32:34 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
12:32:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:32:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:32:34 INFO - 'gecko_profile': False,
12:32:34 INFO - 'gecko_profile_interval': 0,
12:32:34 INFO - 'installer_path': 'installer.exe',
12:32:34 INFO - 'jsd_code_coverage': False,
12:32:34 INFO - 'log_level': 'info',
12:32:34 INFO - 'log_name': 'talos',
12:32:34 INFO - 'log_to_console': True,
12:32:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
12:32:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
12:32:34 INFO - 'opt_config_files': (),
12:32:34 INFO - 'pip_index': False,
12:32:34 INFO - 'suite': 'g4-e10s',
12:32:34 INFO - 'system_bits': '32',
12:32:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:32:34 INFO - 'title': 'talos-linux64-ix-015',
12:32:34 INFO - 'tooltool_cache': '/builds/tooltool_cache',
12:32:34 INFO - 'use_talos_json': True,
12:32:34 INFO - 'verify': 'False',
12:32:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
12:32:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:32:34 INFO - 'work_dir': 'build'}
12:32:34 INFO - [mozharness: 2017-11-28 20:32:34.757709Z] Running clobber step.
12:32:34 INFO - Running pre-action listener: _resource_record_pre_action
12:32:34 INFO - Running main action method: clobber
12:32:34 INFO - rmtree: /builds/slave/test/build
12:32:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
12:32:36 INFO - Running post-action listener: _resource_record_post_action
12:32:36 INFO - [mozharness: 2017-11-28 20:32:36.333433Z] Finished clobber step (success)
12:32:36 INFO - [mozharness: 2017-11-28 20:32:36.333531Z] Running read-buildbot-config step.
12:32:36 INFO - Running pre-action listener: _resource_record_pre_action
12:32:36 INFO - Running main action method: read_buildbot_config
12:32:36 INFO - Using buildbot properties:
12:32:36 INFO - {
12:32:36 INFO - "project": "",
12:32:36 INFO - "product": "firefox",
12:32:36 INFO - "who": "ffxbld@noreply.mozilla.org",
12:32:36 INFO - "installer_path": "public/build/target.tar.bz2",
12:32:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
12:32:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
12:32:36 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
12:32:36 INFO - "stage_platform": "linux64-qr",
12:32:36 INFO - "basedir": "/builds/slave/test",
12:32:36 INFO - "buildnumber": 113,
12:32:36 INFO - "platform": "ubuntu64_hw_qr",
12:32:36 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
12:32:36 INFO - "slavebuilddir": "test",
12:32:36 INFO - "taskId": "Au8FcbjETY-cEhqvYAWmnw",
12:32:36 INFO - "branch": "mozilla-central",
12:32:36 INFO - "script_repo_revision": "production",
12:32:36 INFO - "revision": "c2248f85346939d3e0b01f57276c440ccb2d16a1",
12:32:36 INFO - "slavename": "talos-linux64-ix-015",
12:32:36 INFO - "repo_path": "mozilla-central"
12:32:36 INFO - }
12:32:36 INFO - Finding installer, test and symbols from parent task.
12:32:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Au8FcbjETY-cEhqvYAWmnw'}, attempt #1
12:32:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Au8FcbjETY-cEhqvYAWmnw'}, attempt #1
12:32:37 INFO - Task dependencies: WqWhMWF1QxO_7kGXKf-XIw
12:32:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw'}, attempt #1
12:32:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2
12:32:37 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.test_packages.json
12:32:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.crashreporter-symbols.zip
12:32:37 INFO - Running post-action listener: _resource_record_post_action
12:32:37 INFO - [mozharness: 2017-11-28 20:32:37.708748Z] Finished read-buildbot-config step (success)
12:32:37 INFO - [mozharness: 2017-11-28 20:32:37.708962Z] Running download-and-extract step.
12:32:37 INFO - Running pre-action listener: _resource_record_pre_action
12:32:37 INFO - Running main action method: download_and_extract
12:32:37 INFO - mkdir: /builds/slave/test/build/tests
12:32:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:37 INFO - trying https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.test_packages.json
12:32:37 INFO - Downloading https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
12:32:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
12:32:38 INFO - Downloaded 989 bytes.
12:32:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
12:32:38 INFO - Using the following test package requirements:
12:32:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
12:32:38 INFO - u'common': [u'target.common.tests.zip'],
12:32:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
12:32:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
12:32:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
12:32:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
12:32:38 INFO - u'mozbase': [u'target.common.tests.zip'],
12:32:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
12:32:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
12:32:38 INFO - u'web-platform': [u'target.common.tests.zip',
12:32:38 INFO - u'target.web-platform.tests.tar.gz'],
12:32:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
12:32:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
12:32:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.common.tests.zip
12:32:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.common.tests.zip'}, attempt #1
12:32:38 INFO - Fetch https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.common.tests.zip into memory
12:32:39 INFO - Content-Length response header: 59113671
12:32:39 INFO - Bytes received: 59113671
12:32:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.talos.tests.zip
12:32:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
12:32:50 INFO - Fetch https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.talos.tests.zip into memory
12:32:51 INFO - Content-Length response header: 18744795
12:32:51 INFO - Bytes received: 18744795
12:32:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:51 INFO - trying https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2
12:32:51 INFO - Downloading https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
12:32:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
12:32:53 INFO - Downloaded 62535090 bytes.
12:32:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2
12:32:53 INFO - mkdir: /builds/slave/test/properties
12:32:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
12:32:53 INFO - Writing to file /builds/slave/test/properties/build_url
12:32:53 INFO - Contents:
12:32:53 INFO - build_url:https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2
12:32:53 INFO - Running post-action listener: _resource_record_post_action
12:32:53 INFO - Running post-action listener: find_tests_for_verification
12:32:53 INFO - Running post-action listener: set_extra_try_arguments
12:32:53 INFO - [mozharness: 2017-11-28 20:32:53.040713Z] Finished download-and-extract step (success)
12:32:53 INFO - [mozharness: 2017-11-28 20:32:53.040807Z] Running populate-webroot step.
12:32:53 INFO - Running pre-action listener: _resource_record_pre_action
12:32:53 INFO - Running main action method: populate_webroot
12:32:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
12:32:53 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['tresize',
12:32:53 INFO - 'tcanvasmark']},
12:32:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
12:32:53 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['dromaeo_css',
12:32:53 INFO - 'kraken']},
12:32:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:32:53 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:32:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'tests': ['damp', 'tps']},
12:32:53 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['damp', 'tps']},
12:32:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
12:32:53 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['dromaeo_dom']},
12:32:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
12:32:53 INFO - 'glvideo',
12:32:53 INFO - 'displaylist_mutate']},
12:32:53 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['basic_compositor_video',
12:32:53 INFO - 'glvideo']},
12:32:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
12:32:53 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['ts_paint_webext',
12:32:53 INFO - 'tp5o_webext']},
12:32:53 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
12:32:53 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:32:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:32:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:32:53 INFO - 'talos_options': ['--mitmproxy',
12:32:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:32:53 INFO - '--firstNonBlankPaint'],
12:32:53 INFO - 'tests': ['tp6_google_heavy',
12:32:53 INFO - 'tp6_youtube_heavy',
12:32:53 INFO - 'tp6_amazon_heavy',
12:32:53 INFO - 'tp6_facebook_heavy']},
12:32:53 INFO - 'other-e10s': {'tests': ['a11yr',
12:32:53 INFO - 'ts_paint',
12:32:53 INFO - 'tpaint',
12:32:53 INFO - 'sessionrestore',
12:32:53 INFO - 'sessionrestore_many_windows',
12:32:53 INFO - 'sessionrestore_no_auto_restore',
12:32:53 INFO - 'tabpaint',
12:32:53 INFO - 'cpstartup']},
12:32:53 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['a11yr',
12:32:53 INFO - 'ts_paint',
12:32:53 INFO - 'tpaint',
12:32:53 INFO - 'sessionrestore',
12:32:53 INFO - 'sessionrestore_many_windows',
12:32:53 INFO - 'sessionrestore_no_auto_restore',
12:32:53 INFO - 'tabpaint',
12:32:53 INFO - 'cpstartup']},
12:32:53 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
12:32:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
12:32:53 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['perf_reftest_singletons']},
12:32:53 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['perf_reftest']},
12:32:53 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
12:32:53 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['speedometer']},
12:32:53 INFO - 'svgr-e10s': {'tests': ['tsvgx',
12:32:53 INFO - 'tsvgr_opacity',
12:32:53 INFO - 'tart',
12:32:53 INFO - 'tscrollx',
12:32:53 INFO - 'tsvg_static']},
12:32:53 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['tsvgx',
12:32:53 INFO - 'tsvgr_opacity',
12:32:53 INFO - 'tart',
12:32:53 INFO - 'tscrollx',
12:32:53 INFO - 'tsvg_static']},
12:32:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
12:32:53 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'talos_options': ['--disable-stylo'],
12:32:53 INFO - 'tests': ['tp5o']},
12:32:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:32:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:32:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:32:53 INFO - 'talos_options': ['--mitmproxy',
12:32:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:32:53 INFO - '--firstNonBlankPaint'],
12:32:53 INFO - 'tests': ['tp6_google',
12:32:53 INFO - 'tp6_youtube',
12:32:53 INFO - 'tp6_amazon',
12:32:53 INFO - 'tp6_facebook']},
12:32:53 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:32:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:32:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:32:53 INFO - 'talos_options': ['--disable-stylo',
12:32:53 INFO - '--mitmproxy',
12:32:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:32:53 INFO - '--firstNonBlankPaint'],
12:32:53 INFO - 'tests': ['tp6_google',
12:32:53 INFO - 'tp6_youtube',
12:32:53 INFO - 'tp6_amazon',
12:32:53 INFO - 'tp6_facebook']},
12:32:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:32:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:32:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:32:53 INFO - 'talos_options': ['--stylo-threads=1',
12:32:53 INFO - '--mitmproxy',
12:32:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:32:53 INFO - '--firstNonBlankPaint'],
12:32:53 INFO - 'tests': ['tp6_google',
12:32:53 INFO - 'tp6_youtube',
12:32:53 INFO - 'tp6_amazon',
12:32:53 INFO - 'tp6_facebook']},
12:32:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'talos_options': ['--xperf_path',
12:32:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:32:53 INFO - 'tests': ['tp5n']},
12:32:53 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:32:53 INFO - 'talos_options': ['--disable-stylo',
12:32:53 INFO - '--xperf_path',
12:32:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:32:53 INFO - 'tests': ['tp5n']}}}
12:32:53 INFO - Running post-action listener: _resource_record_post_action
12:32:53 INFO - [mozharness: 2017-11-28 20:32:53.065203Z] Finished populate-webroot step (success)
12:32:53 INFO - [mozharness: 2017-11-28 20:32:53.065353Z] Running create-virtualenv step.
12:32:53 INFO - Running pre-action listener: _resource_record_pre_action
12:32:53 INFO - Running main action method: create_virtualenv
12:32:53 INFO - Creating virtualenv /builds/slave/test/build/venv
12:32:53 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
12:32:53 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
12:32:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
12:32:53 INFO - Using real prefix '/usr'
12:32:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python
12:32:53 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
12:32:55 INFO - Installing setuptools, pip, wheel...done.
12:32:55 INFO - Return code: 0
12:32:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
12:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:32:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:32:55 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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:32:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
12:32:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
12:32:55 INFO - Using env: {'DISPLAY': ':0',
12:32:55 INFO - 'HOME': '/home/cltbld',
12:32:55 INFO - 'LANG': 'en_US.UTF-8',
12:32:55 INFO - 'LANGUAGE': 'en_US:en',
12:32:55 INFO - 'LOGNAME': 'cltbld',
12:32:55 INFO - 'MAIL': '/var/mail/cltbld',
12:32:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:32:55 INFO - 'MOZ_NO_REMOTE': '1',
12:32:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:32:55 INFO - 'NO_EM_RESTART': '1',
12:32:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:32:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:32:55 INFO - 'PWD': '/builds/slave/test',
12:32:55 INFO - 'SHELL': '/bin/bash',
12:32:55 INFO - 'SHLVL': '1',
12:32:55 INFO - 'TERM': 'linux',
12:32:55 INFO - 'TMOUT': '86400',
12:32:55 INFO - 'USER': 'cltbld',
12:32:55 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:32:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:32:55 INFO - '_': '/tools/buildbot/bin/python'}
12:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple
12:32:56 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
12:32:56 INFO - Return code: 0
12:32:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
12:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:32:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:32:56 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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:32:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
12:32:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
12:32:56 INFO - Using env: {'DISPLAY': ':0',
12:32:56 INFO - 'HOME': '/home/cltbld',
12:32:56 INFO - 'LANG': 'en_US.UTF-8',
12:32:56 INFO - 'LANGUAGE': 'en_US:en',
12:32:56 INFO - 'LOGNAME': 'cltbld',
12:32:56 INFO - 'MAIL': '/var/mail/cltbld',
12:32:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:32:56 INFO - 'MOZ_NO_REMOTE': '1',
12:32:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:32:56 INFO - 'NO_EM_RESTART': '1',
12:32:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:32:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:32:56 INFO - 'PWD': '/builds/slave/test',
12:32:56 INFO - 'SHELL': '/bin/bash',
12:32:56 INFO - 'SHLVL': '1',
12:32:56 INFO - 'TERM': 'linux',
12:32:56 INFO - 'TMOUT': '86400',
12:32:56 INFO - 'USER': 'cltbld',
12:32:56 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:32:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:32:56 INFO - '_': '/tools/buildbot/bin/python'}
12:32:56 INFO - Ignoring indexes: https://pypi.python.org/simple
12:32:56 INFO - Collecting psutil>=3.1.1
12:32:57 INFO - Installing collected packages: psutil
12:32:57 INFO - Successfully installed psutil-3.1.1
12:32:57 INFO - Return code: 0
12:32:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
12:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:32:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:32:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
12:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
12:32:57 INFO - Using env: {'DISPLAY': ':0',
12:32:57 INFO - 'HOME': '/home/cltbld',
12:32:57 INFO - 'LANG': 'en_US.UTF-8',
12:32:57 INFO - 'LANGUAGE': 'en_US:en',
12:32:57 INFO - 'LOGNAME': 'cltbld',
12:32:57 INFO - 'MAIL': '/var/mail/cltbld',
12:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:32:57 INFO - 'MOZ_NO_REMOTE': '1',
12:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:32:57 INFO - 'NO_EM_RESTART': '1',
12:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:32:57 INFO - 'PWD': '/builds/slave/test',
12:32:57 INFO - 'SHELL': '/bin/bash',
12:32:57 INFO - 'SHLVL': '1',
12:32:57 INFO - 'TERM': 'linux',
12:32:57 INFO - 'TMOUT': '86400',
12:32:57 INFO - 'USER': 'cltbld',
12:32:57 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:32:57 INFO - '_': '/tools/buildbot/bin/python'}
12:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple
12:32:58 INFO - Collecting mozsystemmonitor==0.3
12:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
12:32:59 INFO - Installing collected packages: mozsystemmonitor
12:32:59 INFO - Successfully installed mozsystemmonitor-0.3
12:32:59 INFO - Return code: 0
12:32:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
12:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:32:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
12:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
12:32:59 INFO - Using env: {'DISPLAY': ':0',
12:32:59 INFO - 'HOME': '/home/cltbld',
12:32:59 INFO - 'LANG': 'en_US.UTF-8',
12:32:59 INFO - 'LANGUAGE': 'en_US:en',
12:32:59 INFO - 'LOGNAME': 'cltbld',
12:32:59 INFO - 'MAIL': '/var/mail/cltbld',
12:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:32:59 INFO - 'MOZ_NO_REMOTE': '1',
12:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:32:59 INFO - 'NO_EM_RESTART': '1',
12:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:32:59 INFO - 'PWD': '/builds/slave/test',
12:32:59 INFO - 'SHELL': '/bin/bash',
12:32:59 INFO - 'SHLVL': '1',
12:32:59 INFO - 'TERM': 'linux',
12:32:59 INFO - 'TMOUT': '86400',
12:32:59 INFO - 'USER': 'cltbld',
12:32:59 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:32:59 INFO - '_': '/tools/buildbot/bin/python'}
12:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple
12:32:59 INFO - Collecting jsonschema==2.5.1
12:33:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
12:33:00 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
12:33:02 INFO - Installing collected packages: functools32, jsonschema
12:33:02 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
12:33:02 INFO - Return code: 0
12:33:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
12:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:33:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
12:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
12:33:02 INFO - Using env: {'DISPLAY': ':0',
12:33:02 INFO - 'HOME': '/home/cltbld',
12:33:02 INFO - 'LANG': 'en_US.UTF-8',
12:33:02 INFO - 'LANGUAGE': 'en_US:en',
12:33:02 INFO - 'LOGNAME': 'cltbld',
12:33:02 INFO - 'MAIL': '/var/mail/cltbld',
12:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:33:02 INFO - 'MOZ_NO_REMOTE': '1',
12:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:33:02 INFO - 'NO_EM_RESTART': '1',
12:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:33:02 INFO - 'PWD': '/builds/slave/test',
12:33:02 INFO - 'SHELL': '/bin/bash',
12:33:02 INFO - 'SHLVL': '1',
12:33:02 INFO - 'TERM': 'linux',
12:33:02 INFO - 'TMOUT': '86400',
12:33:02 INFO - 'USER': 'cltbld',
12:33:02 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:33:02 INFO - '_': '/tools/buildbot/bin/python'}
12:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple
12:33:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
12:33:02 INFO - Return code: 0
12:33:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
12:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:33:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:33:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:33:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
12:33:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
12:33:02 INFO - Using env: {'DISPLAY': ':0',
12:33:02 INFO - 'HOME': '/home/cltbld',
12:33:02 INFO - 'LANG': 'en_US.UTF-8',
12:33:02 INFO - 'LANGUAGE': 'en_US:en',
12:33:02 INFO - 'LOGNAME': 'cltbld',
12:33:02 INFO - 'MAIL': '/var/mail/cltbld',
12:33:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:33:02 INFO - 'MOZ_NO_REMOTE': '1',
12:33:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:33:02 INFO - 'NO_EM_RESTART': '1',
12:33:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:33:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:33:02 INFO - 'PWD': '/builds/slave/test',
12:33:02 INFO - 'SHELL': '/bin/bash',
12:33:02 INFO - 'SHLVL': '1',
12:33:02 INFO - 'TERM': 'linux',
12:33:02 INFO - 'TMOUT': '86400',
12:33:02 INFO - 'USER': 'cltbld',
12:33:02 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:33:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:33:02 INFO - '_': '/tools/buildbot/bin/python'}
12:33:02 INFO - Ignoring indexes: https://pypi.python.org/simple
12:33:02 INFO - Collecting blobuploader==1.2.4
12:33:04 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
12:33:05 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
12:33:06 INFO - Installing collected packages: requests, docopt, blobuploader
12:33:06 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
12:33:06 INFO - Return code: 0
12:33:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:33:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:33:06 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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:33:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
12:33:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:33:06 INFO - Using env: {'DISPLAY': ':0',
12:33:06 INFO - 'HOME': '/home/cltbld',
12:33:06 INFO - 'LANG': 'en_US.UTF-8',
12:33:06 INFO - 'LANGUAGE': 'en_US:en',
12:33:06 INFO - 'LOGNAME': 'cltbld',
12:33:06 INFO - 'MAIL': '/var/mail/cltbld',
12:33:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:33:06 INFO - 'MOZ_NO_REMOTE': '1',
12:33:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:33:06 INFO - 'NO_EM_RESTART': '1',
12:33:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:33:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:33:06 INFO - 'PWD': '/builds/slave/test',
12:33:06 INFO - 'SHELL': '/bin/bash',
12:33:06 INFO - 'SHLVL': '1',
12:33:06 INFO - 'TERM': 'linux',
12:33:06 INFO - 'TMOUT': '86400',
12:33:06 INFO - 'USER': 'cltbld',
12:33:06 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:33:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:33:06 INFO - '_': '/tools/buildbot/bin/python'}
12:33:06 INFO - Ignoring indexes: https://pypi.python.org/simple
12:33:06 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:33:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:33:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:33:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:33:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:33:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:33:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:33:10 INFO - Running setup.py install for manifestparser: started
12:33:10 INFO - Running setup.py install for manifestparser: finished with status 'done'
12:33:10 INFO - Running setup.py install for mozcrash: started
12:33:10 INFO - Running setup.py install for mozcrash: finished with status 'done'
12:33:10 INFO - Running setup.py install for mozdebug: started
12:33:10 INFO - Running setup.py install for mozdebug: finished with status 'done'
12:33:10 INFO - Running setup.py install for mozdevice: started
12:33:10 INFO - Running setup.py install for mozdevice: finished with status 'done'
12:33:10 INFO - Running setup.py install for mozfile: started
12:33:11 INFO - Running setup.py install for mozfile: finished with status 'done'
12:33:11 INFO - Running setup.py install for mozhttpd: started
12:33:11 INFO - Running setup.py install for mozhttpd: finished with status 'done'
12:33:11 INFO - Running setup.py install for mozinfo: started
12:33:11 INFO - Running setup.py install for mozinfo: finished with status 'done'
12:33:11 INFO - Running setup.py install for mozInstall: started
12:33:11 INFO - Running setup.py install for mozInstall: finished with status 'done'
12:33:11 INFO - Running setup.py install for mozleak: started
12:33:12 INFO - Running setup.py install for mozleak: finished with status 'done'
12:33:12 INFO - Running setup.py install for mozlog: started
12:33:12 INFO - Running setup.py install for mozlog: finished with status 'done'
12:33:12 INFO - Running setup.py install for moznetwork: started
12:33:12 INFO - Running setup.py install for moznetwork: finished with status 'done'
12:33:12 INFO - Running setup.py install for mozprocess: started
12:33:12 INFO - Running setup.py install for mozprocess: finished with status 'done'
12:33:12 INFO - Running setup.py install for mozprofile: started
12:33:12 INFO - Running setup.py install for mozprofile: finished with status 'done'
12:33:12 INFO - Running setup.py install for mozrunner: started
12:33:13 INFO - Running setup.py install for mozrunner: finished with status 'done'
12:33:13 INFO - Running setup.py install for mozscreenshot: started
12:33:13 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
12:33:13 INFO - Running setup.py install for moztest: started
12:33:13 INFO - Running setup.py install for moztest: finished with status 'done'
12:33:13 INFO - Running setup.py install for mozversion: started
12:33:13 INFO - Running setup.py install for mozversion: finished with status 'done'
12:33:14 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
12:33:14 INFO - Return code: 0
12:33:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:33:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:33:14 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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:33:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
12:33:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:33:14 INFO - Using env: {'DISPLAY': ':0',
12:33:14 INFO - 'HOME': '/home/cltbld',
12:33:14 INFO - 'LANG': 'en_US.UTF-8',
12:33:14 INFO - 'LANGUAGE': 'en_US:en',
12:33:14 INFO - 'LOGNAME': 'cltbld',
12:33:14 INFO - 'MAIL': '/var/mail/cltbld',
12:33:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:33:14 INFO - 'MOZ_NO_REMOTE': '1',
12:33:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:33:14 INFO - 'NO_EM_RESTART': '1',
12:33:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:33:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:33:14 INFO - 'PWD': '/builds/slave/test',
12:33:14 INFO - 'SHELL': '/bin/bash',
12:33:14 INFO - 'SHLVL': '1',
12:33:14 INFO - 'TERM': 'linux',
12:33:14 INFO - 'TMOUT': '86400',
12:33:14 INFO - 'USER': 'cltbld',
12:33:14 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:33:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:33:14 INFO - '_': '/tools/buildbot/bin/python'}
12:33:14 INFO - Ignoring indexes: https://pypi.python.org/simple
12:33:14 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:33:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:33:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:33:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
12:33:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:33:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
12:33:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:33:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
12:33:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
12:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
12:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:33:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
12:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
12:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
12:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:33:17 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))
12:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
12:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:33:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
12:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:33:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
12:33:18 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:33:19 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
12:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
12:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:33:19 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:33:19 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:33:20 INFO - Installing collected packages: six, blessings
12:33:20 INFO - Successfully installed blessings-1.6 six-1.10.0
12:33:20 INFO - Return code: 0
12:33:20 INFO - Done creating virtualenv /builds/slave/test/build/venv.
12:33:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:33:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:33:21 INFO - Reading from file tmpfile_stdout
12:33:21 INFO - Reading from file tmpfile_stderr
12:33:21 INFO - Current package versions:
12:33:21 INFO - blessings == 1.6
12:33:21 INFO - blobuploader == 1.2.4
12:33:21 INFO - docopt == 0.6.1
12:33:21 INFO - functools32 == 3.2.3.post2
12:33:21 INFO - jsonschema == 2.5.1
12:33:21 INFO - manifestparser == 1.2
12:33:21 INFO - mozInstall == 1.14
12:33:21 INFO - mozcrash == 1.0
12:33:21 INFO - mozdebug == 0.1
12:33:21 INFO - mozdevice == 0.51
12:33:21 INFO - mozfile == 1.2
12:33:21 INFO - mozhttpd == 0.7
12:33:21 INFO - mozinfo == 0.10
12:33:21 INFO - mozleak == 0.1
12:33:21 INFO - mozlog == 3.5
12:33:21 INFO - moznetwork == 0.27
12:33:21 INFO - mozprocess == 0.25
12:33:21 INFO - mozprofile == 0.29
12:33:21 INFO - mozrunner == 6.14
12:33:21 INFO - mozscreenshot == 0.1
12:33:21 INFO - mozsystemmonitor == 0.3
12:33:21 INFO - moztest == 0.8
12:33:21 INFO - mozversion == 1.4
12:33:21 INFO - psutil == 3.1.1
12:33:21 INFO - requests == 1.2.3
12:33:21 INFO - six == 1.10.0
12:33:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:33:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:33:21 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 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:33:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
12:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:33:21 INFO - Using env: {'DISPLAY': ':0',
12:33:21 INFO - 'HOME': '/home/cltbld',
12:33:21 INFO - 'LANG': 'en_US.UTF-8',
12:33:21 INFO - 'LANGUAGE': 'en_US:en',
12:33:21 INFO - 'LOGNAME': 'cltbld',
12:33:21 INFO - 'MAIL': '/var/mail/cltbld',
12:33:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:33:21 INFO - 'MOZ_NO_REMOTE': '1',
12:33:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:33:21 INFO - 'NO_EM_RESTART': '1',
12:33:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:33:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:33:21 INFO - 'PWD': '/builds/slave/test',
12:33:21 INFO - 'SHELL': '/bin/bash',
12:33:21 INFO - 'SHLVL': '1',
12:33:21 INFO - 'TERM': 'linux',
12:33:21 INFO - 'TMOUT': '86400',
12:33:21 INFO - 'USER': 'cltbld',
12:33:21 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:33:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:33:21 INFO - '_': '/tools/buildbot/bin/python'}
12:33:21 INFO - Ignoring indexes: https://pypi.python.org/simple
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:33:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
12:33:21 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
12:33:22 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:33:23 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
12:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:33:23 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:33:23 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:33:24 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
12:33:24 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:33:25 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
12:33:25 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:33:26 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
12:33:26 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:33:27 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
12:33:27 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
12:33:28 INFO - Found existing installation: requests 1.2.3
12:33:28 INFO - Uninstalling requests-1.2.3:
12:33:28 INFO - Successfully uninstalled requests-1.2.3
12:33:28 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
12:33:28 INFO - Return code: 0
12:33:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
12:33: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')]}
12:33:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:33: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')]}
12:33:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:33: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1453d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1432a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d44c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, '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': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:33: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', 'jsonschema'] in /builds/slave/test/build
12:33: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 jsonschema
12:33:28 INFO - Using env: {'DISPLAY': ':0',
12:33:28 INFO - 'HOME': '/home/cltbld',
12:33:28 INFO - 'LANG': 'en_US.UTF-8',
12:33:28 INFO - 'LANGUAGE': 'en_US:en',
12:33:28 INFO - 'LOGNAME': 'cltbld',
12:33:28 INFO - 'MAIL': '/var/mail/cltbld',
12:33:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:33:28 INFO - 'MOZ_NO_REMOTE': '1',
12:33:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:33:28 INFO - 'NO_EM_RESTART': '1',
12:33:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:33:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:33:28 INFO - 'PWD': '/builds/slave/test',
12:33:28 INFO - 'SHELL': '/bin/bash',
12:33:28 INFO - 'SHLVL': '1',
12:33:28 INFO - 'TERM': 'linux',
12:33:28 INFO - 'TMOUT': '86400',
12:33:28 INFO - 'USER': 'cltbld',
12:33:28 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:33:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:33:28 INFO - '_': '/tools/buildbot/bin/python'}
12:33:28 INFO - Ignoring indexes: https://pypi.python.org/simple
12:33:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
12:33:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
12:33:28 INFO - Return code: 0
12:33:28 INFO - Running post-action listener: _resource_record_post_action
12:33:28 INFO - Running post-action listener: _start_resource_monitoring
12:33:28 INFO - Starting resource monitoring.
12:33:28 INFO - [mozharness: 2017-11-28 20:33:28.886533Z] Finished create-virtualenv step (success)
12:33:28 INFO - [mozharness: 2017-11-28 20:33:28.886986Z] Running install step.
12:33:28 INFO - Running pre-action listener: _resource_record_pre_action
12:33:28 INFO - Running main action method: install
12:33:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:33:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:33:29 INFO - Reading from file tmpfile_stdout
12:33:29 INFO - Reading from file tmpfile_stderr
12:33:29 INFO - Detecting whether we're running mozinstall >=1.0...
12:33:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
12:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
12:33:29 INFO - Reading from file tmpfile_stdout
12:33:29 INFO - Output received:
12:33:29 INFO - Usage: mozinstall [options] installer
12:33:29 INFO - Options:
12:33:29 INFO - -h, --help show this help message and exit
12:33:29 INFO - -d DEST, --destination=DEST
12:33:29 INFO - Directory to install application into. [default:
12:33:29 INFO - "/builds/slave/test"]
12:33:29 INFO - --app=APP Application being installed. [default: firefox]
12:33:29 INFO - mkdir: /builds/slave/test/build/application
12:33:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
12:33:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
12:33:47 INFO - Reading from file tmpfile_stdout
12:33:47 INFO - Output received:
12:33:47 INFO - /builds/slave/test/build/application/firefox/firefox
12:33:47 INFO - Running post-action listener: _resource_record_post_action
12:33:47 INFO - [mozharness: 2017-11-28 20:33:47.841207Z] Finished install step (success)
12:33:47 INFO - [mozharness: 2017-11-28 20:33:47.841350Z] Running setup-mitmproxy step.
12:33:47 INFO - Running pre-action listener: _resource_record_pre_action
12:33:47 INFO - Running main action method: setup_mitmproxy
12:33:47 INFO - Skipping: mitmproxy is not required
12:33:47 INFO - Running post-action listener: _resource_record_post_action
12:33:47 INFO - [mozharness: 2017-11-28 20:33:47.842898Z] Finished setup-mitmproxy step (success)
12:33:47 INFO - [mozharness: 2017-11-28 20:33:47.843199Z] Running run-tests step.
12:33:47 INFO - Running pre-action listener: _resource_record_pre_action
12:33:47 INFO - Running pre-action listener: _set_gcov_prefix
12:33:47 INFO - Running main action method: run_tests
12:33:47 WARNING - Try message not found.
12:33:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
12:33:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
12:33:47 INFO - Python 2.7.3
12:33:47 INFO - Return code: 0
12:33:47 INFO - grabbing minidump binary from tooltool
12:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:33:47 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 0x15d1660>, '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 0x15d2560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15d29f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
12:33:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
12:33:47 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
12:33:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
12:33:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
12:33:47 INFO - Return code: 0
12:33:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
12:33:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
12:33:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
12:33:47 INFO - ENV: RUST_BACKTRACE is now full
12:33:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
12:33:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
12:33:47 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/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
12:33:47 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/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-015 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
12:33:47 INFO - Using env: {'DISPLAY': ':0',
12:33:47 INFO - 'HOME': '/home/cltbld',
12:33:47 INFO - 'LANG': 'en_US.UTF-8',
12:33:47 INFO - 'LANGUAGE': 'en_US:en',
12:33:47 INFO - 'LOGNAME': 'cltbld',
12:33:47 INFO - 'MAIL': '/var/mail/cltbld',
12:33:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:33:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:33:47 INFO - 'MOZ_ACCELERATED': '1',
12:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:33:47 INFO - 'MOZ_NO_REMOTE': '1',
12:33:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:33:47 INFO - 'MOZ_WEBRENDER': '1',
12:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:33:47 INFO - 'NO_EM_RESTART': '1',
12:33:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:33:47 INFO - 'PWD': '/builds/slave/test',
12:33:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:33:47 INFO - 'RUST_BACKTRACE': 'full',
12:33:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:33:47 INFO - 'SHELL': '/bin/bash',
12:33:47 INFO - 'SHLVL': '1',
12:33:47 INFO - 'TERM': 'linux',
12:33:47 INFO - 'TMOUT': '86400',
12:33:47 INFO - 'USER': 'cltbld',
12:33:47 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:33:47 INFO - '_': '/tools/buildbot/bin/python'}
12:33:47 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/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-015', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
12:33:48 INFO - mozversion application_buildid: 20171128194549
12:33:48 INFO - mozversion application_changeset: c2248f85346939d3e0b01f57276c440ccb2d16a1
12:33:48 INFO - mozversion application_display_name: Nightly
12:33:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:33:48 INFO - mozversion application_name: Firefox
12:33:48 INFO - mozversion application_remotingname: firefox
12:33:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
12:33:48 INFO - mozversion application_vendor: Mozilla
12:33:48 INFO - mozversion application_version: 59.0a1
12:33:48 INFO - mozversion platform_buildid: 20171128194549
12:33:48 INFO - mozversion platform_changeset: c2248f85346939d3e0b01f57276c440ccb2d16a1
12:33:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
12:33:48 INFO - mozversion platform_version: 59.0a1
12:33:48 INFO - using testdate: 1511901228
12:33:48 INFO - actual date: 1511901228
12:33:48 INFO - starting webserver on 'localhost:37695'
12:33:48 INFO - SUITE-START | Running 3 tests
12:33:48 INFO - TEST-START | basic_compositor_video
12:33:48 INFO - Initialising browser for basic_compositor_video test...
12:33:48 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:33:48 INFO - Installing Add-ons
12:33:48 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37695/getInfo.html -profile /tmp/tmpcxC9O7/profile
12:33:48 INFO - TEST-INFO | started process 16622 (/builds/slave/test/build/application/firefox/firefox http://localhost:37695/getInfo.html)
12:34:01 INFO - TEST-INFO | 16622: exit 0
12:34:01 INFO - Browser initialized.
12:34:01 INFO - Running cycle 1/1 for basic_compositor_video test...
12:34:01 INFO - Using env: {'DISPLAY': ':0',
12:34:01 INFO - 'HOME': '/home/cltbld',
12:34:01 INFO - 'JSGC_DISABLE_POISONING': '1',
12:34:01 INFO - 'LANG': 'en_US.UTF-8',
12:34:01 INFO - 'LANGUAGE': 'en_US:en',
12:34:01 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:34:01 INFO - 'LOGNAME': 'cltbld',
12:34:01 INFO - 'MAIL': '/var/mail/cltbld',
12:34:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:34:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:34:01 INFO - 'MOZ_ACCELERATED': '1',
12:34:01 INFO - 'MOZ_CRASHREPORTER': '1',
12:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:34:01 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:34:01 INFO - 'MOZ_NO_REMOTE': '1',
12:34:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:34:01 INFO - 'MOZ_WEBRENDER': '1',
12:34:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:34:01 INFO - 'NO_EM_RESTART': '1',
12:34:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:34:01 INFO - 'PWD': '/builds/slave/test',
12:34:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:34:01 INFO - 'RUST_BACKTRACE': 'full',
12:34:01 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:34:01 INFO - 'SHELL': '/bin/bash',
12:34:01 INFO - 'SHLVL': '1',
12:34:01 INFO - 'TERM': 'linux',
12:34:01 INFO - 'TMOUT': '86400',
12:34:01 INFO - 'USER': 'cltbld',
12:34:01 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:34:01 INFO - '_': '/tools/buildbot/bin/python'}
12:34:01 INFO - TEST-INFO | started process 16920 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcxC9O7/profile)
12:34:02 INFO - PID 16920 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:02 INFO - PID 16920 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:03 INFO - PID 16920 |
12:34:03 INFO - PID 16920 | (/builds/slave/test/build/application/firefox/firefox:16982): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:34:03 INFO - PID 16920 |
12:34:04 INFO - PID 16920 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:34:48 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.506470342522974 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.463343596059113 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.512973199329983 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.497571785268416 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0773282051282056 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.058042813455656 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.196545454545455 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.125319587628864 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.318021582733815 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.329350649350649 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.7107378335949734 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.6198845265588915 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.010794392523371 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.8284470989761115 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.977011627906972 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.804200680272101 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.978604651162797 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.783288135593219 ms/frame
12:34:48 INFO - PID 16920 |
12:34:48 INFO - PID 16920 | Cycle 1(1): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:35:34 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4695473251028806 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4735661310259585 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.523288477712364 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5099560853199505 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.080143737166326 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.067584355828219 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.14996542185339 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.1257113402061805 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.21974683544304 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.2021638655462175 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.702805642633228 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.684145199063234 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.978151162790697 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781830508474575 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9939160839160825 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.781135593220331 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.011343457943933 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780644067796613 ms/frame
12:35:34 INFO - PID 16920 |
12:35:34 INFO - PID 16920 | Cycle 1(2): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:36:20 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.483729304635762 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.467213316892725 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5171937919463088 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.506516290726818 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.08379753340185 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0638973966309346 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.3351806358381495 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.4261393805309766 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.525196078431375 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.386195175438596 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.831256038647342 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.796247002398081 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.0617411764705835 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759341216216214 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.993892773892767 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758969594594591 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.009848130841131 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.803537414965979 ms/frame
12:36:20 INFO - PID 16920 |
12:36:20 INFO - PID 16920 | Cycle 1(3): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:37:06 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.467319078947368 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4605166051660516 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.515180217937971 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5000562500000014 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.07763076923077 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0637748851454805 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.451624629080119 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.256659574468087 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.545363636363635 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.5572437357631 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.695039123630674 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673352803738317 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.042781690140842 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781779661016949 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.028255269320831 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.806173469387778 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.993624708624707 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.758513513513503 ms/frame
12:37:06 INFO - PID 16920 |
12:37:06 INFO - PID 16920 | Cycle 1(4): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:37:52 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.478059454995871 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.463208128078817 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5105815899581594 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.50043125 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0774358974358993 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0629249617151584 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.167013888888889 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.124484536082474 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.26822901849218 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.184414225941418 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.801383999999997 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.785143540669862 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.995396270396274 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.80545918367347 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9791976744186055 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758766891891896 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.029262295081956 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.780220338983054 ms/frame
12:37:52 INFO - PID 16920 |
12:37:52 INFO - PID 16920 | Cycle 1(5): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:38:38 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4945552784704907 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4545521472392644 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.521147058823529 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.497933832709113 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0812012320328543 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0633001531393553 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.167555555555552 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.1686458333333345 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.244016973125885 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.247271762208069 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.808373397435896 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.7178891509433925 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.99498834498835 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.75937499999998 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010362149532722 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758834459459453 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.977476744186052 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.803741496598625 ms/frame
12:38:38 INFO - PID 16920 |
12:38:38 INFO - PID 16920 | Cycle 1(6): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:39:24 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4712767710049426 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4672441430332923 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.519492023509656 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.510138017565872 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0897837281153455 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0581269113149867 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.725393700787402 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.5168848758465 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.5250754147812975 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.524705882352945 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.666625194401243 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673235981308412 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.994743589743596 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.8047448979591625 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.011425233644858 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.851780821917812 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.028185011709593 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.806309523809524 ms/frame
12:39:24 INFO - PID 16920 |
12:39:24 INFO - PID 16920 | Cycle 1(7): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:40:10 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4696831275720164 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4642241379310343 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5090050167224085 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5095357590966114 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.077317948717949 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.072104454685098 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.15644736842105 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.134132231404963 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.5592705167173255 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.525033936651589 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.696220657276993 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.652313953488378 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.042265258215957 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.826296928327656 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010116822429911 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.782779661016943 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.009591121495317 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.783101694915252 ms/frame
12:40:10 INFO - PID 16920 |
12:40:10 INFO - PID 16920 | Cycle 1(8): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:40:56 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4740766694146745 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460959409594096 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5192191435768256 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.500306250000001 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0740727459016393 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.072173579109064 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.2987535816618925 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.275202991452987 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.292868383404865 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.1860146443514665 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.747460443037975 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.651395348837214 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.026896955503514 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.8029931972789175 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.044377934272298 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.735252525252534 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.016857476635506 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.757111486486497 ms/frame
12:40:56 INFO - PID 16920 |
12:40:56 INFO - PID 16920 | Cycle 1(9): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:41:42 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4856669428334714 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4729604449938196 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5147485331098074 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.497153558052435 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0804928131416833 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.063514548238898 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.832391304347825 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.728841607565019 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.286528571428571 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.203508403361343 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.673084112149531 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.674532710280375 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.026299765807953 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759425675675678 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.962946635730856 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758091216216205 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.994603729603724 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.757364864864866 ms/frame
12:41:42 INFO - PID 16920 |
12:41:42 INFO - PID 16920 | Cycle 1(10): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:42:28 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4879767827529022 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4641194581280788 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.513170016750419 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.506466165413535 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.077917948717948 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.073179723502303 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.552921092564492 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.567009132420088 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.399061583577712 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.434733924611971 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.673333333333337 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.597908045977012 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.026920374707266 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781135593220331 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010292056074763 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.712852348993289 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.976627906976738 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.80379251700681 ms/frame
12:42:28 INFO - PID 16920 |
12:42:28 INFO - PID 16920 | Cycle 1(11): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:43:14 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4797603305785128 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4638793103448275 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.523238015138772 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5031289111389223 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_startup = 3.083627954779035 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0678757668711643 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_startup = 4.316575539568346 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1_inclip = 4.255723404255316 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.238241525423728 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.150508298755191 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_startup = 4.674330218068539 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.480p.60fps.webm_scale_2_inclip = 4.620450346420321 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.04262910798122 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781271186440673 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.009859813084115 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.782491525423726 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.030843091334908 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.781084745762706 ms/frame
12:43:14 INFO - PID 16920 |
12:43:14 INFO - PID 16920 | Cycle 1(12): loaded http://localhost:37695/tests/video/video_playback.html (next: http://localhost:37695/tests/video/video_playback.html)
12:43:15 INFO - PID 16920 | __start_tp_report
12:43:15 INFO - PID 16920 | _x_x_mozilla_page_load
12:43:15 INFO - PID 16920 | _x_x_mozilla_page_load_details
12:43:15 INFO - PID 16920 | |i|pagename|runs|
12:43:15 INFO - PID 16920 | |0;240p.120fps.mp4_scale_1_startup;2.506470342522974;2.4695473251028806;2.483729304635762;2.467319078947368;2.478059454995871;2.4945552784704907;2.4712767710049426;2.4696831275720164;2.4740766694146745;2.4856669428334714;2.4879767827529022;2.4797603305785128
12:43:15 INFO - PID 16920 | |1;240p.120fps.mp4_scale_1_inclip;2.463343596059113;2.4735661310259585;2.467213316892725;2.4605166051660516;2.463208128078817;2.4545521472392644;2.4672441430332923;2.4642241379310343;2.460959409594096;2.4729604449938196;2.4641194581280788;2.4638793103448275
12:43:15 INFO - PID 16920 | |2;240p.120fps.mp4_scale_1.1_startup;2.512973199329983;2.523288477712364;2.5171937919463088;2.515180217937971;2.5105815899581594;2.521147058823529;2.519492023509656;2.5090050167224085;2.5192191435768256;2.5147485331098074;2.513170016750419;2.523238015138772
12:43:15 INFO - PID 16920 | |3;240p.120fps.mp4_scale_1.1_inclip;2.497571785268416;2.5099560853199505;2.506516290726818;2.5000562500000014;2.50043125;2.497933832709113;2.510138017565872;2.5095357590966114;2.500306250000001;2.497153558052435;2.506466165413535;2.5031289111389223
12:43:15 INFO - PID 16920 | |4;240p.120fps.mp4_scale_2_startup;3.0773282051282056;3.080143737166326;3.08379753340185;3.07763076923077;3.0774358974358993;3.0812012320328543;3.0897837281153455;3.077317948717949;3.0740727459016393;3.0804928131416833;3.077917948717948;3.083627954779035
12:43:15 INFO - PID 16920 | |5;240p.120fps.mp4_scale_2_inclip;3.058042813455656;3.067584355828219;3.0638973966309346;3.0637748851454805;3.0629249617151584;3.0633001531393553;3.0581269113149867;3.072104454685098;3.072173579109064;3.063514548238898;3.073179723502303;3.0678757668711643
12:43:15 INFO - PID 16920 | |6;480p.60fps.webm_scale_1_startup;4.196545454545455;4.14996542185339;4.3351806358381495;4.451624629080119;4.167013888888889;4.167555555555552;4.725393700787402;4.15644736842105;4.2987535816618925;4.832391304347825;4.552921092564492;4.316575539568346
12:43:15 INFO - PID 16920 | |7;480p.60fps.webm_scale_1_inclip;4.125319587628864;4.1257113402061805;4.4261393805309766;4.256659574468087;4.124484536082474;4.1686458333333345;4.5168848758465;4.134132231404963;4.275202991452987;4.728841607565019;4.567009132420088;4.255723404255316
12:43:15 INFO - PID 16920 | |8;480p.60fps.webm_scale_1.1_startup;4.318021582733815;4.21974683544304;4.525196078431375;4.545363636363635;4.26822901849218;4.244016973125885;4.5250754147812975;4.5592705167173255;4.292868383404865;4.286528571428571;4.399061583577712;4.238241525423728
12:43:15 INFO - PID 16920 | |9;480p.60fps.webm_scale_1.1_inclip;4.329350649350649;4.2021638655462175;4.386195175438596;4.5572437357631;4.184414225941418;4.247271762208069;4.524705882352945;4.525033936651589;4.1860146443514665;4.203508403361343;4.434733924611971;4.150508298755191
12:43:15 INFO - PID 16920 | |10;480p.60fps.webm_scale_2_startup;4.7107378335949734;4.702805642633228;4.831256038647342;4.695039123630674;4.801383999999997;4.808373397435896;4.666625194401243;4.696220657276993;4.747460443037975;4.673084112149531;4.673333333333337;4.674330218068539
12:43:15 INFO - PID 16920 | |11;480p.60fps.webm_scale_2_inclip;4.6198845265588915;4.684145199063234;4.796247002398081;4.673352803738317;4.785143540669862;4.7178891509433925;4.673235981308412;4.652313953488378;4.651395348837214;4.674532710280375;4.597908045977012;4.620450346420321
12:43:15 INFO - PID 16920 | |12;1080p.60fps.mp4_scale_1_startup;7.010794392523371;6.978151162790697;7.0617411764705835;7.042781690140842;6.995396270396274;6.99498834498835;6.994743589743596;7.042265258215957;7.026896955503514;7.026299765807953;7.026920374707266;7.04262910798122
12:43:15 INFO - PID 16920 | |13;1080p.60fps.mp4_scale_1_inclip;6.8284470989761115;6.781830508474575;6.759341216216214;6.781779661016949;6.80545918367347;6.75937499999998;6.8047448979591625;6.826296928327656;6.8029931972789175;6.759425675675678;6.781135593220331;6.781271186440673
12:43:15 INFO - PID 16920 | |14;1080p.60fps.mp4_scale_1.1_startup;6.977011627906972;6.9939160839160825;6.993892773892767;7.028255269320831;6.9791976744186055;7.010362149532722;7.011425233644858;7.010116822429911;7.044377934272298;6.962946635730856;7.010292056074763;7.009859813084115
12:43:15 INFO - PID 16920 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.804200680272101;6.781135593220331;6.758969594594591;6.806173469387778;6.758766891891896;6.758834459459453;6.851780821917812;6.782779661016943;6.735252525252534;6.758091216216205;6.712852348993289;6.782491525423726
12:43:15 INFO - PID 16920 | |16;1080p.60fps.mp4_scale_2_startup;6.978604651162797;7.011343457943933;7.009848130841131;6.993624708624707;7.029262295081956;6.977476744186052;7.028185011709593;7.009591121495317;7.016857476635506;6.994603729603724;6.976627906976738;7.030843091334908
12:43:15 INFO - PID 16920 | |17;1080p.60fps.mp4_scale_2_inclip;6.783288135593219;6.780644067796613;6.803537414965979;6.758513513513503;6.780220338983054;6.803741496598625;6.806309523809524;6.783101694915252;6.757111486486497;6.757364864864866;6.80379251700681;6.781084745762706
12:43:15 INFO - PID 16920 | __end_tp_report
12:43:15 INFO - PID 16920 | __start_cc_report
12:43:15 INFO - PID 16920 | _x_x_mozilla_cycle_collect,577
12:43:15 INFO - PID 16920 | __end_cc_report
12:43:15 INFO - PID 16920 | __startTimestamp1511901795044__endTimestamp
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | ------- Summary: start -------
12:43:15 INFO - PID 16920 | Number of tests: 18
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.48 Median:2.48 stddev:0.01 (0.5%) stddev-sans-first:0.01
12:43:15 INFO - PID 16920 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.46 Median:2.46 stddev:0.01 (0.2%) stddev-sans-first:0.01
12:43:15 INFO - PID 16920 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.52 Median:2.52 stddev:0.00 (0.2%) stddev-sans-first:0.00
12:43:15 INFO - PID 16920 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.50 Median:2.50 stddev:0.01 (0.2%) stddev-sans-first:0.00
12:43:15 INFO - PID 16920 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.08 Median:3.08 stddev:0.00 (0.1%) stddev-sans-first:0.00
12:43:15 INFO - PID 16920 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.07 Median:3.07 stddev:0.01 (0.2%) stddev-sans-first:0.00
12:43:15 INFO - PID 16920 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.36 Median:4.33 stddev:0.23 (5.4%) stddev-sans-first:0.24
12:43:15 INFO - PID 16920 | Values: 4.2 4.1 4.3 4.5 4.2 4.2 4.7 4.2 4.3 4.8 4.6 4.3
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.31 Median:4.27 stddev:0.20 (4.8%) stddev-sans-first:0.21
12:43:15 INFO - PID 16920 | Values: 4.1 4.1 4.4 4.3 4.1 4.2 4.5 4.1 4.3 4.7 4.6 4.3
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.37 Median:4.36 stddev:0.13 (3.1%) stddev-sans-first:0.14
12:43:15 INFO - PID 16920 | Values: 4.3 4.2 4.5 4.5 4.3 4.2 4.5 4.6 4.3 4.3 4.4 4.2
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.33 Median:4.36 stddev:0.15 (3.5%) stddev-sans-first:0.16
12:43:15 INFO - PID 16920 | Values: 4.3 4.2 4.4 4.6 4.2 4.2 4.5 4.5 4.2 4.2 4.4 4.2
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.72 Median:4.71 stddev:0.06 (1.3%) stddev-sans-first:0.06
12:43:15 INFO - PID 16920 | Values: 4.7 4.7 4.8 4.7 4.8 4.8 4.7 4.7 4.7 4.7 4.7 4.7
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.68 Median:4.67 stddev:0.06 (1.3%) stddev-sans-first:0.06
12:43:15 INFO - PID 16920 | Values: 4.6 4.7 4.8 4.7 4.8 4.7 4.7 4.7 4.7 4.7 4.6 4.6
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.02 Median:7.03 stddev:0.03 (0.4%) stddev-sans-first:0.03
12:43:15 INFO - PID 16920 | Values: 7.0 7.0 7.1 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.79 Median:6.79 stddev:0.02 (0.4%) stddev-sans-first:0.02
12:43:15 INFO - PID 16920 | Values: 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.00 Median:7.01 stddev:0.02 (0.3%) stddev-sans-first:0.02
12:43:15 INFO - PID 16920 | Values: 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.77 Median:6.78 stddev:0.04 (0.5%) stddev-sans-first:0.04
12:43:15 INFO - PID 16920 | Values: 6.8 6.8 6.8 6.8 6.8 6.8 6.9 6.8 6.7 6.8 6.7 6.8
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.00 Median:7.01 stddev:0.02 (0.3%) stddev-sans-first:0.02
12:43:15 INFO - PID 16920 | Values: 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
12:43:15 INFO - PID 16920 |
12:43:15 INFO - PID 16920 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.78 Median:6.78 stddev:0.02 (0.3%) stddev-sans-first:0.02
12:43:15 INFO - PID 16920 | Values: 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8
12:43:15 INFO - PID 16920 | -------- Summary: end --------
12:43:15 INFO - PID 16920 |
12:43:20 INFO - TEST-INFO | 16920: exit 0
12:43:20 INFO - TEST-OK | basic_compositor_video | took 572704ms
12:43:20 INFO - TEST-START | glvideo
12:43:20 INFO - Initialising browser for glvideo test...
12:43:20 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:43:20 INFO - Installing Add-ons
12:43:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37695/getInfo.html -profile /tmp/tmpjDm1Cg/profile
12:43:20 INFO - TEST-INFO | started process 18195 (/builds/slave/test/build/application/firefox/firefox http://localhost:37695/getInfo.html)
12:43:32 INFO - TEST-INFO | 18195: exit 0
12:43:32 INFO - Browser initialized.
12:43:32 INFO - Running cycle 1/1 for glvideo test...
12:43:32 INFO - Using env: {'DISPLAY': ':0',
12:43:32 INFO - 'HOME': '/home/cltbld',
12:43:32 INFO - 'JSGC_DISABLE_POISONING': '1',
12:43:32 INFO - 'LANG': 'en_US.UTF-8',
12:43:32 INFO - 'LANGUAGE': 'en_US:en',
12:43:32 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:43:32 INFO - 'LOGNAME': 'cltbld',
12:43:32 INFO - 'MAIL': '/var/mail/cltbld',
12:43:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:43:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:43:32 INFO - 'MOZ_ACCELERATED': '1',
12:43:32 INFO - 'MOZ_CRASHREPORTER': '1',
12:43:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:43:32 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:43:32 INFO - 'MOZ_NO_REMOTE': '1',
12:43:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:43:32 INFO - 'MOZ_WEBRENDER': '1',
12:43:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:43:32 INFO - 'NO_EM_RESTART': '1',
12:43:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:43:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:43:32 INFO - 'PWD': '/builds/slave/test',
12:43:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:43:32 INFO - 'RUST_BACKTRACE': 'full',
12:43:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:43:32 INFO - 'SHELL': '/bin/bash',
12:43:32 INFO - 'SHLVL': '1',
12:43:32 INFO - 'TERM': 'linux',
12:43:32 INFO - 'TMOUT': '86400',
12:43:32 INFO - 'USER': 'cltbld',
12:43:32 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:43:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:43:32 INFO - '_': '/tools/buildbot/bin/python'}
12:43:32 INFO - TEST-INFO | started process 18543 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjDm1Cg/profile)
12:43:33 INFO - PID 18543 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:43:33 INFO - PID 18543 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:43:33 INFO - PID 18543 |
12:43:33 INFO - PID 18543 | (/builds/slave/test/build/application/firefox/firefox:18605): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:43:33 INFO - PID 18543 |
12:43:35 INFO - PID 18543 | [talos glvideo result] Mean tick time across 100 ticks: 6.868850000000001 ms
12:43:35 INFO - PID 18543 | Cycle 1(1): loaded http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html)
12:43:35 INFO - PID 18543 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:43:36 INFO - PID 18543 | [talos glvideo result] Mean tick time across 100 ticks: 6.501950000000001 ms
12:43:36 INFO - PID 18543 | Cycle 1(2): loaded http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html)
12:43:37 INFO - PID 18543 | [talos glvideo result] Mean tick time across 100 ticks: 6.501949999999999 ms
12:43:37 INFO - PID 18543 | Cycle 1(3): loaded http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html)
12:43:39 INFO - PID 18543 | [talos glvideo result] Mean tick time across 100 ticks: 6.6507000000000005 ms
12:43:39 INFO - PID 18543 | Cycle 1(4): loaded http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html)
12:43:40 INFO - PID 18543 | [talos glvideo result] Mean tick time across 100 ticks: 6.586749999999999 ms
12:43:40 INFO - PID 18543 | Cycle 1(5): loaded http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:37695/tests/webgl/benchmarks/video/video_upload.html)
12:43:40 INFO - PID 18543 | __start_tp_report
12:43:40 INFO - PID 18543 | _x_x_mozilla_page_load
12:43:40 INFO - PID 18543 | _x_x_mozilla_page_load_details
12:43:40 INFO - PID 18543 | |i|pagename|runs|
12:43:40 INFO - PID 18543 | |0;Mean tick time across 100 ticks: ;6.868850000000001;6.501950000000001;6.501949999999999;6.6507000000000005;6.586749999999999
12:43:40 INFO - PID 18543 | __end_tp_report
12:43:40 INFO - PID 18543 | __start_cc_report
12:43:40 INFO - PID 18543 | _x_x_mozilla_cycle_collect,285
12:43:40 INFO - PID 18543 | __end_cc_report
12:43:40 INFO - PID 18543 | __startTimestamp1511901820899__endTimestamp
12:43:40 INFO - PID 18543 |
12:43:40 INFO - PID 18543 | ------- Summary: start -------
12:43:40 INFO - PID 18543 | Number of tests: 1
12:43:40 INFO - PID 18543 |
12:43:40 INFO - PID 18543 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.62 Median:6.59 stddev:0.15 (2.3%) stddev-sans-first:0.07
12:43:40 INFO - PID 18543 | Values: 6.9 6.5 6.5 6.7 6.6
12:43:40 INFO - PID 18543 | -------- Summary: end --------
12:43:40 INFO - PID 18543 |
12:43:41 INFO - PID 18543 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:43:46 INFO - TEST-INFO | 18543: exit 0
12:43:46 INFO - TEST-OK | glvideo | took 25738ms
12:43:46 INFO - TEST-START | displaylist_mutate
12:43:46 INFO - Initialising browser for displaylist_mutate test...
12:43:46 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:43:46 INFO - Installing Add-ons
12:43:46 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:37695/getInfo.html -profile /tmp/tmplQQ9ui/profile
12:43:46 INFO - TEST-INFO | started process 18782 (/builds/slave/test/build/application/firefox/firefox http://localhost:37695/getInfo.html)
12:43:58 INFO - TEST-INFO | 18782: exit 0
12:43:58 INFO - Browser initialized.
12:43:58 INFO - Running cycle 1/1 for displaylist_mutate test...
12:43:58 INFO - Using env: {'DISPLAY': ':0',
12:43:58 INFO - 'HOME': '/home/cltbld',
12:43:58 INFO - 'JSGC_DISABLE_POISONING': '1',
12:43:58 INFO - 'LANG': 'en_US.UTF-8',
12:43:58 INFO - 'LANGUAGE': 'en_US:en',
12:43:58 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:43:58 INFO - 'LOGNAME': 'cltbld',
12:43:58 INFO - 'MAIL': '/var/mail/cltbld',
12:43:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:43:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:43:58 INFO - 'MOZ_ACCELERATED': '1',
12:43:58 INFO - 'MOZ_CRASHREPORTER': '1',
12:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:43:58 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:43:58 INFO - 'MOZ_NO_REMOTE': '1',
12:43:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:43:58 INFO - 'MOZ_WEBRENDER': '1',
12:43:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:43:58 INFO - 'NO_EM_RESTART': '1',
12:43:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:43:58 INFO - 'PWD': '/builds/slave/test',
12:43:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:43:58 INFO - 'RUST_BACKTRACE': 'full',
12:43:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:43:58 INFO - 'SHELL': '/bin/bash',
12:43:58 INFO - 'SHLVL': '1',
12:43:58 INFO - 'TERM': 'linux',
12:43:58 INFO - 'TMOUT': '86400',
12:43:58 INFO - 'USER': 'cltbld',
12:43:58 INFO - 'XDG_SESSION_COOKIE': '20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457',
12:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:43:58 INFO - '_': '/tools/buildbot/bin/python'}
12:43:58 INFO - TEST-INFO | started process 19073 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplQQ9ui/profile)
12:43:59 INFO - PID 19073 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:43:59 INFO - PID 19073 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:43:59 INFO - PID 19073 |
12:43:59 INFO - PID 19073 | (/builds/slave/test/build/application/firefox/firefox:19134): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:43:59 INFO - PID 19073 |
12:44:06 INFO - PID 19073 | Cycle 1(1): loaded http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html)
12:44:06 INFO - PID 19073 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
12:44:13 INFO - PID 19073 | Cycle 1(2): loaded http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html)
12:44:20 INFO - PID 19073 | Cycle 1(3): loaded http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html)
12:44:27 INFO - PID 19073 | Cycle 1(4): loaded http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html)
12:44:34 INFO - PID 19073 | Cycle 1(5): loaded http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:37695/tests/layout/benchmarks/displaylist_mutate.html)
12:44:34 INFO - PID 19073 | __start_tp_report
12:44:34 INFO - PID 19073 | _x_x_mozilla_page_load
12:44:34 INFO - PID 19073 | _x_x_mozilla_page_load_details
12:44:34 INFO - PID 19073 | |i|pagename|runs|
12:44:34 INFO - PID 19073 | |0;/displaylist_mutate.html;5920.71;6113.195000000001;6113.625000000001;6157.885;6101.83
12:44:34 INFO - PID 19073 | __end_tp_report
12:44:34 INFO - PID 19073 | __start_cc_report
12:44:34 INFO - PID 19073 | _x_x_mozilla_cycle_collect,248
12:44:34 INFO - PID 19073 | __end_cc_report
12:44:34 INFO - PID 19073 | __startTimestamp1511901874258__endTimestamp
12:44:34 INFO - PID 19073 |
12:44:34 INFO - PID 19073 | ------- Summary: start -------
12:44:34 INFO - PID 19073 | Number of tests: 1
12:44:34 INFO - PID 19073 |
12:44:34 INFO - PID 19073 | [#0] /displaylist_mutate.html Cycles:5 Average:6081.45 Median:6113.20 stddev:92.38 (1.5%) stddev-sans-first:24.78
12:44:34 INFO - PID 19073 | Values: 5920.7 6113.2 6113.6 6157.9 6101.8
12:44:34 INFO - PID 19073 | -------- Summary: end --------
12:44:34 INFO - PID 19073 |
12:44:34 INFO - PID 19073 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:44:40 INFO - TEST-INFO | 19073: exit 0
12:44:40 INFO - TEST-OK | displaylist_mutate | took 53449ms
12:44:40 INFO - SUITE-END | took 651s
12:44:40 INFO - Completed test suite (00:10:51)
12:44:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.393952080699969, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.506470342522974, 2.4695473251028806, 2.483729304635762, 2.467319078947368, 2.478059454995871, 2.4945552784704907, 2.4712767710049426, 2.4696831275720164, 2.4740766694146745, 2.4856669428334714, 2.4879767827529022, 2.4797603305785128], "value": 2.478059454995871, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.463343596059113, 2.4735661310259585, 2.467213316892725, 2.4605166051660516, 2.463208128078817, 2.4545521472392644, 2.4672441430332923, 2.4642241379310343, 2.460959409594096, 2.4729604449938196, 2.4641194581280788, 2.4638793103448275], "value": 2.4641194581280788, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.512973199329983, 2.523288477712364, 2.5171937919463088, 2.515180217937971, 2.5105815899581594, 2.521147058823529, 2.519492023509656, 2.5090050167224085, 2.5192191435768256, 2.5147485331098074, 2.513170016750419, 2.523238015138772], "value": 2.5171937919463088, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.497571785268416, 2.5099560853199505, 2.506516290726818, 2.5000562500000014, 2.50043125, 2.497933832709113, 2.510138017565872, 2.5095357590966114, 2.500306250000001, 2.497153558052435, 2.506466165413535, 2.5031289111389223], "value": 2.5031289111389223, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0773282051282056, 3.080143737166326, 3.08379753340185, 3.07763076923077, 3.0774358974358993, 3.0812012320328543, 3.0897837281153455, 3.077317948717949, 3.0740727459016393, 3.0804928131416833, 3.077917948717948, 3.083627954779035], "value": 3.080143737166326, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.058042813455656, 3.067584355828219, 3.0638973966309346, 3.0637748851454805, 3.0629249617151584, 3.0633001531393553, 3.0581269113149867, 3.072104454685098, 3.072173579109064, 3.063514548238898, 3.073179723502303, 3.0678757668711643], "value": 3.0638973966309346, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.196545454545455, 4.14996542185339, 4.3351806358381495, 4.451624629080119, 4.167013888888889, 4.167555555555552, 4.725393700787402, 4.15644736842105, 4.2987535816618925, 4.832391304347825, 4.552921092564492, 4.316575539568346], "value": 4.316575539568346, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.125319587628864, 4.1257113402061805, 4.4261393805309766, 4.256659574468087, 4.124484536082474, 4.1686458333333345, 4.5168848758465, 4.134132231404963, 4.275202991452987, 4.728841607565019, 4.567009132420088, 4.255723404255316], "value": 4.256659574468087, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.318021582733815, 4.21974683544304, 4.525196078431375, 4.545363636363635, 4.26822901849218, 4.244016973125885, 4.5250754147812975, 4.5592705167173255, 4.292868383404865, 4.286528571428571, 4.399061583577712, 4.238241525423728], "value": 4.292868383404865, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.329350649350649, 4.2021638655462175, 4.386195175438596, 4.5572437357631, 4.184414225941418, 4.247271762208069, 4.524705882352945, 4.525033936651589, 4.1860146443514665, 4.203508403361343, 4.434733924611971, 4.150508298755191], "value": 4.247271762208069, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.7107378335949734, 4.702805642633228, 4.831256038647342, 4.695039123630674, 4.801383999999997, 4.808373397435896, 4.666625194401243, 4.696220657276993, 4.747460443037975, 4.673084112149531, 4.673333333333337, 4.674330218068539], "value": 4.696220657276993, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.6198845265588915, 4.684145199063234, 4.796247002398081, 4.673352803738317, 4.785143540669862, 4.7178891509433925, 4.673235981308412, 4.652313953488378, 4.651395348837214, 4.674532710280375, 4.597908045977012, 4.620450346420321], "value": 4.673352803738317, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.010794392523371, 6.978151162790697, 7.0617411764705835, 7.042781690140842, 6.995396270396274, 6.99498834498835, 6.994743589743596, 7.042265258215957, 7.026896955503514, 7.026299765807953, 7.026920374707266, 7.04262910798122], "value": 7.026896955503514, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.8284470989761115, 6.781830508474575, 6.759341216216214, 6.781779661016949, 6.80545918367347, 6.75937499999998, 6.8047448979591625, 6.826296928327656, 6.8029931972789175, 6.759425675675678, 6.781135593220331, 6.781271186440673], "value": 6.781779661016949, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.977011627906972, 6.9939160839160825, 6.993892773892767, 7.028255269320831, 6.9791976744186055, 7.010362149532722, 7.011425233644858, 7.010116822429911, 7.044377934272298, 6.962946635730856, 7.010292056074763, 7.009859813084115], "value": 7.010116822429911, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.804200680272101, 6.781135593220331, 6.758969594594591, 6.806173469387778, 6.758766891891896, 6.758834459459453, 6.851780821917812, 6.782779661016943, 6.735252525252534, 6.758091216216205, 6.712852348993289, 6.782491525423726], "value": 6.758969594594591, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.978604651162797, 7.011343457943933, 7.009848130841131, 6.993624708624707, 7.029262295081956, 6.977476744186052, 7.028185011709593, 7.009591121495317, 7.016857476635506, 6.994603729603724, 6.976627906976738, 7.030843091334908], "value": 7.009848130841131, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.783288135593219, 6.780644067796613, 6.803537414965979, 6.758513513513503, 6.780220338983054, 6.803741496598625, 6.806309523809524, 6.783101694915252, 6.757111486486497, 6.757364864864866, 6.80379251700681, 6.781084745762706], "value": 6.781084745762706, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.868850000000001, 6.501950000000001, 6.501949999999999, 6.6507000000000005, 6.586749999999999], "value": 6.54435, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5920.71, 6113.195000000001, 6113.625000000001, 6157.885, 6101.83], "value": 6113.410000000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
12:44:40 INFO - Return code: 0
12:44:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
12:44:40 INFO - # TBPL SUCCESS #
12:44:40 INFO - Running post-action listener: _package_coverage_data
12:44:40 INFO - Running post-action listener: _resource_record_post_action
12:44:40 INFO - [mozharness: 2017-11-28 20:44:40.372669Z] Finished run-tests step (success)
12:44:40 INFO - Running post-run listener: _resource_record_post_run
12:44:40 INFO - Total resource usage - Wall time: 671s; CPU: 43.0%; Read bytes: 16265216; Write bytes: 354197504; Read time: 10556; Write time: 230880
12:44:40 INFO - TinderboxPrint: CPU usage
43.2%
12:44:40 INFO - TinderboxPrint: I/O read bytes / time
16,265,216 / 10,556
12:44:40 INFO - TinderboxPrint: I/O write bytes / time
354,197,504 / 230,880
12:44:40 INFO - TinderboxPrint: CPU idle
2,844.3 (56.5%)
12:44:40 INFO - TinderboxPrint: CPU system
655.2 (13.0%)
12:44:40 INFO - TinderboxPrint: CPU user
1,510.7 (30.0%)
12:44:40 INFO - TinderboxPrint: Swap in / out
0 / 0
12:44:40 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 7188480; Read time: 0; Write time: 6364
12:44:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
12:44:40 INFO - run-tests - Wall time: 653s; CPU: 44.0%; Read bytes: 16265216; Write bytes: 347009024; Read time: 10556; Write time: 224516
12:44:40 INFO - Running post-run listener: _upload_blobber_files
12:44:40 INFO - Blob upload gear active.
12:44:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
12:44:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
12:44:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
12:44:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
12:44:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:40 INFO - SNIMissingWarning
12:44:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:40 INFO - InsecurePlatformWarning
12:44:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:44:41 INFO - SubjectAltNameWarning
12:44:41 INFO - (blobuploader) - INFO - Open directory for files ...
12:44:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
12:44:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:44:41 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:44:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:41 INFO - InsecurePlatformWarning
12:44:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:44:41 INFO - SubjectAltNameWarning
12:44:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:47 INFO - InsecurePlatformWarning
12:44:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:44:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:44:47 INFO - (blobuploader) - INFO - Done attempting.
12:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
12:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:44:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:47 INFO - InsecurePlatformWarning
12:44:47 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:44:47 INFO - SubjectAltNameWarning
12:44:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:48 INFO - InsecurePlatformWarning
12:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
12:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:44:48 INFO - (blobuploader) - INFO - Done attempting.
12:44:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
12:44:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:44:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:44:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:48 INFO - InsecurePlatformWarning
12:44:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:44:48 INFO - SubjectAltNameWarning
12:44:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:49 INFO - InsecurePlatformWarning
12:44:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
12:44:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:44:49 INFO - (blobuploader) - INFO - Done attempting.
12:44:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
12:44:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:44:49 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:44:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:49 INFO - InsecurePlatformWarning
12:44:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:44:49 INFO - SubjectAltNameWarning
12:44:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:44:50 INFO - InsecurePlatformWarning
12:44:50 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
12:44:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:44:50 INFO - (blobuploader) - INFO - Done attempting.
12:44:50 INFO - (blobuploader) - INFO - Iteration through files over.
12:44:50 INFO - Return code: 0
12:44:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
12:44:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
12:44:50 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8553fc353c3f306da437aec8c9a048a13f213c0d95c48976b3dba427c7d374ffd27ec84e53a23a89e5d263a19b3a056dae97e5c54422349aa10a223dcc7e83f7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b6e473731a9d027ff4fef31b1c49c17debe5a73945521db461865b44eb2789aba6f95aa00b823d2412d8f0ba53da24215fafc102a30aa34e1a88dc8b1156ddd", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54a3a0ed72d39e369bd7cceb8a46f72b8b80aa6052dcd7e5230d459a2f3f95162edea7a38bc4d657d6246d6b670d917a169f07a78a048a6ac711c0b0d511da7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d5fd64334a5e539344fcacb876084da2bf151ce13b3da56779e4fd8dd6d325b9e12b077851239e3c40f3ed82146aaf99627f24bd83499d1d1d5a84b25d73b2"}
12:44:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
12:44:50 INFO - Writing to file /builds/slave/test/properties/blobber_files
12:44:50 INFO - Contents:
12:44:50 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8553fc353c3f306da437aec8c9a048a13f213c0d95c48976b3dba427c7d374ffd27ec84e53a23a89e5d263a19b3a056dae97e5c54422349aa10a223dcc7e83f7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b6e473731a9d027ff4fef31b1c49c17debe5a73945521db461865b44eb2789aba6f95aa00b823d2412d8f0ba53da24215fafc102a30aa34e1a88dc8b1156ddd", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54a3a0ed72d39e369bd7cceb8a46f72b8b80aa6052dcd7e5230d459a2f3f95162edea7a38bc4d657d6246d6b670d917a169f07a78a048a6ac711c0b0d511da7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d5fd64334a5e539344fcacb876084da2bf151ce13b3da56779e4fd8dd6d325b9e12b077851239e3c40f3ed82146aaf99627f24bd83499d1d1d5a84b25d73b2"}
12:44:50 INFO - Running post-run listener: copy_logs_to_upload_dir
12:44:50 INFO - Copying logs to upload dir...
12:44:50 INFO - mkdir: /builds/slave/test/build/upload/logs
12:44:50 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=735.756922
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 15 secs) (at 2017-11-28 12:44:50.361928) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-28 12:44:50.365675) =========
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=20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457
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/8553fc353c3f306da437aec8c9a048a13f213c0d95c48976b3dba427c7d374ffd27ec84e53a23a89e5d263a19b3a056dae97e5c54422349aa10a223dcc7e83f7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b6e473731a9d027ff4fef31b1c49c17debe5a73945521db461865b44eb2789aba6f95aa00b823d2412d8f0ba53da24215fafc102a30aa34e1a88dc8b1156ddd", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54a3a0ed72d39e369bd7cceb8a46f72b8b80aa6052dcd7e5230d459a2f3f95162edea7a38bc4d657d6246d6b670d917a169f07a78a048a6ac711c0b0d511da7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d5fd64334a5e539344fcacb876084da2bf151ce13b3da56779e4fd8dd6d325b9e12b077851239e3c40f3ed82146aaf99627f24bd83499d1d1d5a84b25d73b2"}
build_url:https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011354
build_url: 'https://queue.taskcluster.net/v1/task/WqWhMWF1QxO_7kGXKf-XIw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8553fc353c3f306da437aec8c9a048a13f213c0d95c48976b3dba427c7d374ffd27ec84e53a23a89e5d263a19b3a056dae97e5c54422349aa10a223dcc7e83f7", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b6e473731a9d027ff4fef31b1c49c17debe5a73945521db461865b44eb2789aba6f95aa00b823d2412d8f0ba53da24215fafc102a30aa34e1a88dc8b1156ddd", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a54a3a0ed72d39e369bd7cceb8a46f72b8b80aa6052dcd7e5230d459a2f3f95162edea7a38bc4d657d6246d6b670d917a169f07a78a048a6ac711c0b0d511da7", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/98d5fd64334a5e539344fcacb876084da2bf151ce13b3da56779e4fd8dd6d325b9e12b077851239e3c40f3ed82146aaf99627f24bd83499d1d1d5a84b25d73b2"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-28 12:44:50.395391) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 12:44:50.395704) =========
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=20e666a3d4cfbb71505a330e000001b4-1511901151.583581-1930440457
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004422
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 12:44:50.478185) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-28 12:44:50.478500) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-28 12:44:50.478791) =========
========= Total master_lag: 0.20 =========