builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s-pgo
slave: talos-linux64-ix-062
starttime: 1510952656.55
results: success (0)
revision: 36cad9c45551e29cfb09dd1238c3f56d52776abf
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.547559) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.548044) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.548343) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.566448) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.566863) =========
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=a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-17 13:04:16-- 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% 170K=0.07s
2017-11-17 13:04:16 (170 KB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.295416
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.900069) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.900633) =========
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=a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.019539
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:16.951519) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-17 13:04:16.951866) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 36cad9c45551e29cfb09dd1238c3f56d52776abf --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 36cad9c45551e29cfb09dd1238c3f56d52776abf --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=a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-17 13:04:17,001 truncating revision to first 12 chars
2017-11-17 13:04:17,001 Setting DEBUG logging.
2017-11-17 13:04:17,001 attempt 1/10
2017-11-17 13:04:17,001 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/36cad9c45551?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-17 13:04:17,845 unpacking tar archive at: mozilla-central-36cad9c45551/testing/mozharness/
program finished with exit code 0
elapsedTime=1.432724
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-17 13:04:18.401765) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:18.402106) =========
script_repo_revision: 36cad9c45551e29cfb09dd1238c3f56d52776abf
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:18.402543) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:18.402820) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-17 13:04:18.416912) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 3 secs) (at 2017-11-17 13:04:18.417411) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-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-stylo-disabled-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=a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
13:04:18 INFO - MultiFileLogger online at 20171117 13:04:18 in /builds/slave/test
13:04:18 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
13:04:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
13:04:18 INFO - {'append_to_log': False,
13:04:18 INFO - 'base_work_dir': '/builds/slave/test',
13:04:18 INFO - 'blob_upload_branch': 'Firefox',
13:04:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
13:04:18 INFO - 'branch': 'Firefox',
13:04:18 INFO - 'buildbot_json_path': 'buildprops.json',
13:04:18 INFO - 'code_coverage': False,
13:04:18 INFO - 'config_files': ('talos/linux_config.py',),
13:04:18 INFO - 'default_actions': ('clobber',
13:04:18 INFO - 'read-buildbot-config',
13:04:18 INFO - 'download-and-extract',
13:04:18 INFO - 'populate-webroot',
13:04:18 INFO - 'create-virtualenv',
13:04:18 INFO - 'install',
13:04:18 INFO - 'setup-mitmproxy',
13:04:18 INFO - 'run-tests'),
13:04:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
13:04:18 INFO - 'disable_ccov_upload': False,
13:04:18 INFO - 'disable_stylo': False,
13:04:18 INFO - 'download_minidump_stackwalk': True,
13:04:18 INFO - 'download_symbols': 'ondemand',
13:04:18 INFO - 'e10s': False,
13:04:18 INFO - 'enable_stylo': False,
13:04:18 INFO - 'enable_webrender': False,
13:04:18 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
13:04:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
13:04:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
13:04:18 INFO - 'gecko_profile': False,
13:04:18 INFO - 'gecko_profile_interval': 0,
13:04:18 INFO - 'installer_path': 'installer.exe',
13:04:18 INFO - 'jsd_code_coverage': False,
13:04:18 INFO - 'log_level': 'info',
13:04:18 INFO - 'log_name': 'talos',
13:04:18 INFO - 'log_to_console': True,
13:04:18 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
13:04:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
13:04:18 INFO - 'opt_config_files': (),
13:04:18 INFO - 'pip_index': False,
13:04:18 INFO - 'suite': 'g4-stylo-disabled-e10s',
13:04:18 INFO - 'system_bits': '32',
13:04:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
13:04:18 INFO - 'title': 'talos-linux64-ix-062',
13:04:18 INFO - 'tooltool_cache': '/builds/tooltool_cache',
13:04:18 INFO - 'use_talos_json': True,
13:04:18 INFO - 'verify': 'False',
13:04:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
13:04:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
13:04:18 INFO - 'work_dir': 'build'}
13:04:18 INFO - [mozharness: 2017-11-17 21:04:18.704121Z] Running clobber step.
13:04:18 INFO - Running pre-action listener: _resource_record_pre_action
13:04:18 INFO - Running main action method: clobber
13:04:18 INFO - rmtree: /builds/slave/test/build
13:04:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
13:04:19 INFO - Running post-action listener: _resource_record_post_action
13:04:19 INFO - [mozharness: 2017-11-17 21:04:19.707803Z] Finished clobber step (success)
13:04:19 INFO - [mozharness: 2017-11-17 21:04:19.707898Z] Running read-buildbot-config step.
13:04:19 INFO - Running pre-action listener: _resource_record_pre_action
13:04:19 INFO - Running main action method: read_buildbot_config
13:04:19 INFO - Using buildbot properties:
13:04:19 INFO - {
13:04:19 INFO - "project": "",
13:04:19 INFO - "product": "firefox",
13:04:19 INFO - "who": "ffxbld@noreply.mozilla.org",
13:04:19 INFO - "installer_path": "public/build/target.tar.bz2",
13:04:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
13:04:19 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
13:04:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-stylo-disabled-e10s",
13:04:19 INFO - "stage_platform": "linux64-pgo",
13:04:19 INFO - "basedir": "/builds/slave/test",
13:04:19 INFO - "buildnumber": 80,
13:04:19 INFO - "platform": "ubuntu64_hw",
13:04:19 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
13:04:19 INFO - "slavebuilddir": "test",
13:04:19 INFO - "taskId": "L3bOXR9YT-ywxm_ufrkddQ",
13:04:19 INFO - "branch": "mozilla-central",
13:04:19 INFO - "script_repo_revision": "production",
13:04:19 INFO - "revision": "36cad9c45551e29cfb09dd1238c3f56d52776abf",
13:04:19 INFO - "slavename": "talos-linux64-ix-062",
13:04:19 INFO - "repo_path": "mozilla-central"
13:04:19 INFO - }
13:04:19 INFO - Finding installer, test and symbols from parent task.
13:04:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L3bOXR9YT-ywxm_ufrkddQ'}, attempt #1
13:04:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L3bOXR9YT-ywxm_ufrkddQ'}, attempt #1
13:04:20 INFO - Task dependencies: J1zi1dgLSe2cS-WsHejegw
13:04:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw'}, attempt #1
13:04:21 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:04:21 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json
13:04:21 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip
13:04:21 INFO - Running post-action listener: _resource_record_post_action
13:04:21 INFO - [mozharness: 2017-11-17 21:04:21.067175Z] Finished read-buildbot-config step (success)
13:04:21 INFO - [mozharness: 2017-11-17 21:04:21.067392Z] Running download-and-extract step.
13:04:21 INFO - Running pre-action listener: _resource_record_pre_action
13:04:21 INFO - Running main action method: download_and_extract
13:04:21 INFO - mkdir: /builds/slave/test/build/tests
13:04: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')]}
13:04:21 INFO - trying https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json
13:04:21 INFO - Downloading https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
13:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
13:04:21 INFO - Downloaded 989 bytes.
13:04:21 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
13:04:21 INFO - Using the following test package requirements:
13:04:21 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
13:04:21 INFO - u'common': [u'target.common.tests.zip'],
13:04:21 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
13:04:21 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
13:04:21 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
13:04:21 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
13:04:21 INFO - u'mozbase': [u'target.common.tests.zip'],
13:04:21 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
13:04:21 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
13:04:21 INFO - u'web-platform': [u'target.common.tests.zip',
13:04:21 INFO - u'target.web-platform.tests.tar.gz'],
13:04:21 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
13:04:21 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
13:04:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.common.tests.zip
13:04:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.common.tests.zip'}, attempt #1
13:04:21 INFO - Fetch https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.common.tests.zip into memory
13:04:22 INFO - Content-Length response header: 59740113
13:04:22 INFO - Bytes received: 59740113
13:04:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.talos.tests.zip
13:04:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
13:04:33 INFO - Fetch https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.talos.tests.zip into memory
13:04:34 INFO - Content-Length response header: 18771831
13:04:34 INFO - Bytes received: 18771831
13:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:04:35 INFO - trying https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:04:35 INFO - Downloading https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
13:04:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
13:04:36 INFO - Downloaded 63334895 bytes.
13:04:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:04:36 INFO - mkdir: /builds/slave/test/properties
13:04:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
13:04:36 INFO - Writing to file /builds/slave/test/properties/build_url
13:04:36 INFO - Contents:
13:04:36 INFO - build_url:https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
13:04:36 INFO - Running post-action listener: _resource_record_post_action
13:04:36 INFO - Running post-action listener: find_tests_for_verification
13:04:36 INFO - Running post-action listener: set_extra_try_arguments
13:04:36 INFO - [mozharness: 2017-11-17 21:04:36.452735Z] Finished download-and-extract step (success)
13:04:36 INFO - [mozharness: 2017-11-17 21:04:36.452830Z] Running populate-webroot step.
13:04:36 INFO - Running pre-action listener: _resource_record_pre_action
13:04:36 INFO - Running main action method: populate_webroot
13:04:36 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
13:04:36 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['tresize',
13:04:36 INFO - 'tcanvasmark']},
13:04:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
13:04:36 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['dromaeo_css',
13:04:36 INFO - 'kraken']},
13:04:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:04:36 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
13:04:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'tests': ['damp', 'tps']},
13:04:36 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['damp', 'tps']},
13:04:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
13:04:36 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['dromaeo_dom']},
13:04:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
13:04:36 INFO - 'glvideo',
13:04:36 INFO - 'displaylist_mutate']},
13:04:36 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['basic_compositor_video',
13:04:36 INFO - 'glvideo']},
13:04:36 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
13:04:36 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['ts_paint_webext',
13:04:36 INFO - 'tp5o_webext']},
13:04:36 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
13:04:36 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:04:36 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:04:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:04:36 INFO - 'talos_options': ['--mitmproxy',
13:04:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:04:36 INFO - '--firstNonBlankPaint'],
13:04:36 INFO - 'tests': ['tp6_google_heavy',
13:04:36 INFO - 'tp6_youtube_heavy',
13:04:36 INFO - 'tp6_amazon_heavy',
13:04:36 INFO - 'tp6_facebook_heavy']},
13:04:36 INFO - 'other-e10s': {'tests': ['a11yr',
13:04:36 INFO - 'ts_paint',
13:04:36 INFO - 'tpaint',
13:04:36 INFO - 'sessionrestore',
13:04:36 INFO - 'sessionrestore_many_windows',
13:04:36 INFO - 'sessionrestore_no_auto_restore',
13:04:36 INFO - 'tabpaint',
13:04:36 INFO - 'cpstartup']},
13:04:36 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['a11yr',
13:04:36 INFO - 'ts_paint',
13:04:36 INFO - 'tpaint',
13:04:36 INFO - 'sessionrestore',
13:04:36 INFO - 'sessionrestore_many_windows',
13:04:36 INFO - 'sessionrestore_no_auto_restore',
13:04:36 INFO - 'tabpaint',
13:04:36 INFO - 'cpstartup']},
13:04:36 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
13:04:36 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
13:04:36 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['perf_reftest_singletons']},
13:04:36 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['perf_reftest']},
13:04:36 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
13:04:36 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['speedometer']},
13:04:36 INFO - 'svgr-e10s': {'tests': ['tsvgx',
13:04:36 INFO - 'tsvgr_opacity',
13:04:36 INFO - 'tart',
13:04:36 INFO - 'tscrollx',
13:04:36 INFO - 'tsvg_static']},
13:04:36 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['tsvgx',
13:04:36 INFO - 'tsvgr_opacity',
13:04:36 INFO - 'tart',
13:04:36 INFO - 'tscrollx',
13:04:36 INFO - 'tsvg_static']},
13:04:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
13:04:36 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'talos_options': ['--disable-stylo'],
13:04:36 INFO - 'tests': ['tp5o']},
13:04:36 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:04:36 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:04:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:04:36 INFO - 'talos_options': ['--mitmproxy',
13:04:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:04:36 INFO - '--firstNonBlankPaint'],
13:04:36 INFO - 'tests': ['tp6_google',
13:04:36 INFO - 'tp6_youtube',
13:04:36 INFO - 'tp6_amazon',
13:04:36 INFO - 'tp6_facebook']},
13:04:36 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:04:36 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:04:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:04:36 INFO - 'talos_options': ['--disable-stylo',
13:04:36 INFO - '--mitmproxy',
13:04:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:04:36 INFO - '--firstNonBlankPaint'],
13:04:36 INFO - 'tests': ['tp6_google',
13:04:36 INFO - 'tp6_youtube',
13:04:36 INFO - 'tp6_amazon',
13:04:36 INFO - 'tp6_facebook']},
13:04:36 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
13:04:36 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
13:04:36 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
13:04:36 INFO - 'talos_options': ['--stylo-threads=1',
13:04:36 INFO - '--mitmproxy',
13:04:36 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
13:04:36 INFO - '--firstNonBlankPaint'],
13:04:36 INFO - 'tests': ['tp6_google',
13:04:36 INFO - 'tp6_youtube',
13:04:36 INFO - 'tp6_amazon',
13:04:36 INFO - 'tp6_facebook']},
13:04:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'talos_options': ['--xperf_path',
13:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:04:36 INFO - 'tests': ['tp5n']},
13:04:36 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
13:04:36 INFO - 'talos_options': ['--disable-stylo',
13:04:36 INFO - '--xperf_path',
13:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
13:04:36 INFO - 'tests': ['tp5n']}}}
13:04:36 INFO - Running post-action listener: _resource_record_post_action
13:04:36 INFO - [mozharness: 2017-11-17 21:04:36.471652Z] Finished populate-webroot step (success)
13:04:36 INFO - [mozharness: 2017-11-17 21:04:36.471744Z] Running create-virtualenv step.
13:04:36 INFO - Running pre-action listener: _resource_record_pre_action
13:04:36 INFO - Running main action method: create_virtualenv
13:04:36 INFO - Creating virtualenv /builds/slave/test/build/venv
13:04:36 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
13:04:36 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
13:04:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
13:04:38 INFO - Using real prefix '/usr'
13:04:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python
13:04:38 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
13:04:40 INFO - Installing setuptools, pip, wheel...done.
13:04:40 INFO - Return code: 0
13:04:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
13:04:40 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')]}
13:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:40 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')]}
13:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:40 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:04:40 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
13:04:40 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
13:04:40 INFO - Using env: {'DISPLAY': ':0',
13:04:40 INFO - 'HOME': '/home/cltbld',
13:04:40 INFO - 'LANG': 'en_US.UTF-8',
13:04:40 INFO - 'LANGUAGE': 'en_US:en',
13:04:40 INFO - 'LOGNAME': 'cltbld',
13:04:40 INFO - 'MAIL': '/var/mail/cltbld',
13:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:40 INFO - 'MOZ_NO_REMOTE': '1',
13:04:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:40 INFO - 'NO_EM_RESTART': '1',
13:04:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:40 INFO - 'PWD': '/builds/slave/test',
13:04:40 INFO - 'SHELL': '/bin/bash',
13:04:40 INFO - 'SHLVL': '1',
13:04:40 INFO - 'TERM': 'linux',
13:04:40 INFO - 'TMOUT': '86400',
13:04:40 INFO - 'USER': 'cltbld',
13:04:40 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:40 INFO - '_': '/tools/buildbot/bin/python'}
13:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
13:04:41 INFO - Return code: 0
13:04:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
13:04:41 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')]}
13:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:41 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')]}
13:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:41 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:04:41 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
13:04:41 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
13:04:41 INFO - Using env: {'DISPLAY': ':0',
13:04:41 INFO - 'HOME': '/home/cltbld',
13:04:41 INFO - 'LANG': 'en_US.UTF-8',
13:04:41 INFO - 'LANGUAGE': 'en_US:en',
13:04:41 INFO - 'LOGNAME': 'cltbld',
13:04:41 INFO - 'MAIL': '/var/mail/cltbld',
13:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:41 INFO - 'MOZ_NO_REMOTE': '1',
13:04:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:41 INFO - 'NO_EM_RESTART': '1',
13:04:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:41 INFO - 'PWD': '/builds/slave/test',
13:04:41 INFO - 'SHELL': '/bin/bash',
13:04:41 INFO - 'SHLVL': '1',
13:04:41 INFO - 'TERM': 'linux',
13:04:41 INFO - 'TMOUT': '86400',
13:04:41 INFO - 'USER': 'cltbld',
13:04:41 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:41 INFO - '_': '/tools/buildbot/bin/python'}
13:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:41 INFO - Collecting psutil>=3.1.1
13:04:42 INFO - Installing collected packages: psutil
13:04:42 INFO - Successfully installed psutil-3.1.1
13:04:43 INFO - Return code: 0
13:04:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
13:04:43 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')]}
13:04:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:43 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')]}
13:04:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:43 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:04:43 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
13:04:43 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
13:04:43 INFO - Using env: {'DISPLAY': ':0',
13:04:43 INFO - 'HOME': '/home/cltbld',
13:04:43 INFO - 'LANG': 'en_US.UTF-8',
13:04:43 INFO - 'LANGUAGE': 'en_US:en',
13:04:43 INFO - 'LOGNAME': 'cltbld',
13:04:43 INFO - 'MAIL': '/var/mail/cltbld',
13:04:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:43 INFO - 'MOZ_NO_REMOTE': '1',
13:04:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:43 INFO - 'NO_EM_RESTART': '1',
13:04:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:43 INFO - 'PWD': '/builds/slave/test',
13:04:43 INFO - 'SHELL': '/bin/bash',
13:04:43 INFO - 'SHLVL': '1',
13:04:43 INFO - 'TERM': 'linux',
13:04:43 INFO - 'TMOUT': '86400',
13:04:43 INFO - 'USER': 'cltbld',
13:04:43 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:04:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:43 INFO - '_': '/tools/buildbot/bin/python'}
13:04:43 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:43 INFO - Collecting mozsystemmonitor==0.3
13:04:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
13:04:44 INFO - Installing collected packages: mozsystemmonitor
13:04:44 INFO - Successfully installed mozsystemmonitor-0.3
13:04:44 INFO - Return code: 0
13:04:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
13:04:44 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')]}
13:04:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:44 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')]}
13:04:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:44 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:04:44 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
13:04:44 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
13:04:44 INFO - Using env: {'DISPLAY': ':0',
13:04:44 INFO - 'HOME': '/home/cltbld',
13:04:44 INFO - 'LANG': 'en_US.UTF-8',
13:04:44 INFO - 'LANGUAGE': 'en_US:en',
13:04:44 INFO - 'LOGNAME': 'cltbld',
13:04:44 INFO - 'MAIL': '/var/mail/cltbld',
13:04:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:44 INFO - 'MOZ_NO_REMOTE': '1',
13:04:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:44 INFO - 'NO_EM_RESTART': '1',
13:04:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:44 INFO - 'PWD': '/builds/slave/test',
13:04:44 INFO - 'SHELL': '/bin/bash',
13:04:44 INFO - 'SHLVL': '1',
13:04:44 INFO - 'TERM': 'linux',
13:04:44 INFO - 'TMOUT': '86400',
13:04:44 INFO - 'USER': 'cltbld',
13:04:44 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:04:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:44 INFO - '_': '/tools/buildbot/bin/python'}
13:04:44 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:45 INFO - Collecting jsonschema==2.5.1
13:04:46 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
13:04:46 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
13:04:47 INFO - Installing collected packages: functools32, jsonschema
13:04:47 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
13:04:47 INFO - Return code: 0
13:04:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
13:04: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')]}
13:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04: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')]}
13:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:47 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:04:47 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
13:04:47 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
13:04:47 INFO - Using env: {'DISPLAY': ':0',
13:04:47 INFO - 'HOME': '/home/cltbld',
13:04:47 INFO - 'LANG': 'en_US.UTF-8',
13:04:47 INFO - 'LANGUAGE': 'en_US:en',
13:04:47 INFO - 'LOGNAME': 'cltbld',
13:04:47 INFO - 'MAIL': '/var/mail/cltbld',
13:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:47 INFO - 'MOZ_NO_REMOTE': '1',
13:04:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:47 INFO - 'NO_EM_RESTART': '1',
13:04:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:47 INFO - 'PWD': '/builds/slave/test',
13:04:47 INFO - 'SHELL': '/bin/bash',
13:04:47 INFO - 'SHLVL': '1',
13:04:47 INFO - 'TERM': 'linux',
13:04:47 INFO - 'TMOUT': '86400',
13:04:47 INFO - 'USER': 'cltbld',
13:04:47 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:47 INFO - '_': '/tools/buildbot/bin/python'}
13:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
13:04:48 INFO - Return code: 0
13:04:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
13:04:48 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')]}
13:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:48 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')]}
13:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:48 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:04:48 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
13:04:48 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
13:04:48 INFO - Using env: {'DISPLAY': ':0',
13:04:48 INFO - 'HOME': '/home/cltbld',
13:04:48 INFO - 'LANG': 'en_US.UTF-8',
13:04:48 INFO - 'LANGUAGE': 'en_US:en',
13:04:48 INFO - 'LOGNAME': 'cltbld',
13:04:48 INFO - 'MAIL': '/var/mail/cltbld',
13:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:48 INFO - 'MOZ_NO_REMOTE': '1',
13:04:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:48 INFO - 'NO_EM_RESTART': '1',
13:04:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:48 INFO - 'PWD': '/builds/slave/test',
13:04:48 INFO - 'SHELL': '/bin/bash',
13:04:48 INFO - 'SHLVL': '1',
13:04:48 INFO - 'TERM': 'linux',
13:04:48 INFO - 'TMOUT': '86400',
13:04:48 INFO - 'USER': 'cltbld',
13:04:48 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:48 INFO - '_': '/tools/buildbot/bin/python'}
13:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:48 INFO - Collecting blobuploader==1.2.4
13:04:49 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
13:04:51 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
13:04:52 INFO - Installing collected packages: requests, docopt, blobuploader
13:04:52 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
13:04:52 INFO - Return code: 0
13:04:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:04:52 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:04:52 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
13:04:52 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
13:04:52 INFO - Using env: {'DISPLAY': ':0',
13:04:52 INFO - 'HOME': '/home/cltbld',
13:04:52 INFO - 'LANG': 'en_US.UTF-8',
13:04:52 INFO - 'LANGUAGE': 'en_US:en',
13:04:52 INFO - 'LOGNAME': 'cltbld',
13:04:52 INFO - 'MAIL': '/var/mail/cltbld',
13:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:04:52 INFO - 'MOZ_NO_REMOTE': '1',
13:04:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:04:52 INFO - 'NO_EM_RESTART': '1',
13:04:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:04:52 INFO - 'PWD': '/builds/slave/test',
13:04:52 INFO - 'SHELL': '/bin/bash',
13:04:52 INFO - 'SHLVL': '1',
13:04:52 INFO - 'TERM': 'linux',
13:04:52 INFO - 'TMOUT': '86400',
13:04:52 INFO - 'USER': 'cltbld',
13:04:52 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:04:52 INFO - '_': '/tools/buildbot/bin/python'}
13:04:53 INFO - Ignoring indexes: https://pypi.python.org/simple
13:04:53 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:04:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:04:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:04:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:04:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:04:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:04:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:04:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:04:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:04:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:04:55 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:04:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:04:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:04:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:04:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:04:56 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:04:56 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:04:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
13:04:56 INFO - Running setup.py install for manifestparser: started
13:04:56 INFO - Running setup.py install for manifestparser: finished with status 'done'
13:04:56 INFO - Running setup.py install for mozcrash: started
13:04:56 INFO - Running setup.py install for mozcrash: finished with status 'done'
13:04:56 INFO - Running setup.py install for mozdebug: started
13:04:57 INFO - Running setup.py install for mozdebug: finished with status 'done'
13:04:57 INFO - Running setup.py install for mozdevice: started
13:04:57 INFO - Running setup.py install for mozdevice: finished with status 'done'
13:04:57 INFO - Running setup.py install for mozfile: started
13:04:57 INFO - Running setup.py install for mozfile: finished with status 'done'
13:04:57 INFO - Running setup.py install for mozhttpd: started
13:04:57 INFO - Running setup.py install for mozhttpd: finished with status 'done'
13:04:57 INFO - Running setup.py install for mozinfo: started
13:04:57 INFO - Running setup.py install for mozinfo: finished with status 'done'
13:04:57 INFO - Running setup.py install for mozInstall: started
13:04:58 INFO - Running setup.py install for mozInstall: finished with status 'done'
13:04:58 INFO - Running setup.py install for mozleak: started
13:04:58 INFO - Running setup.py install for mozleak: finished with status 'done'
13:04:58 INFO - Running setup.py install for mozlog: started
13:04:58 INFO - Running setup.py install for mozlog: finished with status 'done'
13:04:58 INFO - Running setup.py install for moznetwork: started
13:04:58 INFO - Running setup.py install for moznetwork: finished with status 'done'
13:04:58 INFO - Running setup.py install for mozprocess: started
13:04:59 INFO - Running setup.py install for mozprocess: finished with status 'done'
13:04:59 INFO - Running setup.py install for mozprofile: started
13:04:59 INFO - Running setup.py install for mozprofile: finished with status 'done'
13:04:59 INFO - Running setup.py install for mozrunner: started
13:04:59 INFO - Running setup.py install for mozrunner: finished with status 'done'
13:04:59 INFO - Running setup.py install for mozscreenshot: started
13:04:59 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
13:04:59 INFO - Running setup.py install for moztest: started
13:05:00 INFO - Running setup.py install for moztest: finished with status 'done'
13:05:00 INFO - Running setup.py install for mozversion: started
13:05:00 INFO - Running setup.py install for mozversion: finished with status 'done'
13:05:00 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
13:05:00 INFO - Return code: 0
13:05:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:00 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:05:00 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
13:05:00 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
13:05:00 INFO - Using env: {'DISPLAY': ':0',
13:05:00 INFO - 'HOME': '/home/cltbld',
13:05:00 INFO - 'LANG': 'en_US.UTF-8',
13:05:00 INFO - 'LANGUAGE': 'en_US:en',
13:05:00 INFO - 'LOGNAME': 'cltbld',
13:05:00 INFO - 'MAIL': '/var/mail/cltbld',
13:05:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:00 INFO - 'MOZ_NO_REMOTE': '1',
13:05:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:00 INFO - 'NO_EM_RESTART': '1',
13:05:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:00 INFO - 'PWD': '/builds/slave/test',
13:05:00 INFO - 'SHELL': '/bin/bash',
13:05:00 INFO - 'SHLVL': '1',
13:05:00 INFO - 'TERM': 'linux',
13:05:00 INFO - 'TMOUT': '86400',
13:05:00 INFO - 'USER': 'cltbld',
13:05:00 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:05:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:00 INFO - '_': '/tools/buildbot/bin/python'}
13:05:00 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:00 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
13:05:01 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))
13:05:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
13:05:01 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))
13:05:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
13:05:01 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))
13:05:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
13:05:01 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))
13:05:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
13:05:02 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))
13:05:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
13:05:02 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))
13:05:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
13:05:02 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))
13:05:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
13:05:02 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))
13:05:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
13:05:02 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))
13:05:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
13:05:03 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))
13:05:03 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
13:05:03 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))
13:05:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
13:05:03 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))
13:05:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
13:05:03 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))
13:05:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
13:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
13:05:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
13:05:04 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))
13:05:04 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
13:05:04 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))
13:05:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
13:05:04 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))
13:05:04 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
13:05:05 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
13:05:05 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))
13:05:05 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))
13:05:05 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))
13:05:05 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))
13:05:05 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))
13:05:05 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))
13:05:05 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
13:05:06 INFO - Installing collected packages: six, blessings
13:05:06 INFO - Successfully installed blessings-1.6 six-1.10.0
13:05:06 INFO - Return code: 0
13:05:06 INFO - Done creating virtualenv /builds/slave/test/build/venv.
13:05:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:05:07 INFO - Reading from file tmpfile_stdout
13:05:07 INFO - Reading from file tmpfile_stderr
13:05:07 INFO - Current package versions:
13:05:07 INFO - blessings == 1.6
13:05:07 INFO - blobuploader == 1.2.4
13:05:07 INFO - docopt == 0.6.1
13:05:07 INFO - functools32 == 3.2.3.post2
13:05:07 INFO - jsonschema == 2.5.1
13:05:07 INFO - manifestparser == 1.2
13:05:07 INFO - mozInstall == 1.14
13:05:07 INFO - mozcrash == 1.0
13:05:07 INFO - mozdebug == 0.1
13:05:07 INFO - mozdevice == 0.51
13:05:07 INFO - mozfile == 1.2
13:05:07 INFO - mozhttpd == 0.7
13:05:07 INFO - mozinfo == 0.10
13:05:07 INFO - mozleak == 0.1
13:05:07 INFO - mozlog == 3.5
13:05:07 INFO - moznetwork == 0.27
13:05:07 INFO - mozprocess == 0.25
13:05:07 INFO - mozprofile == 0.29
13:05:07 INFO - mozrunner == 6.13
13:05:07 INFO - mozscreenshot == 0.1
13:05:07 INFO - mozsystemmonitor == 0.3
13:05:07 INFO - moztest == 0.8
13:05:07 INFO - mozversion == 1.4
13:05:07 INFO - psutil == 3.1.1
13:05:07 INFO - requests == 1.2.3
13:05:07 INFO - six == 1.10.0
13:05:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv
13:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:07 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:05:07 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
13:05:07 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
13:05:07 INFO - Using env: {'DISPLAY': ':0',
13:05:07 INFO - 'HOME': '/home/cltbld',
13:05:07 INFO - 'LANG': 'en_US.UTF-8',
13:05:07 INFO - 'LANGUAGE': 'en_US:en',
13:05:07 INFO - 'LOGNAME': 'cltbld',
13:05:07 INFO - 'MAIL': '/var/mail/cltbld',
13:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:07 INFO - 'MOZ_NO_REMOTE': '1',
13:05:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:07 INFO - 'NO_EM_RESTART': '1',
13:05:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:07 INFO - 'PWD': '/builds/slave/test',
13:05:07 INFO - 'SHELL': '/bin/bash',
13:05:07 INFO - 'SHLVL': '1',
13:05:07 INFO - 'TERM': 'linux',
13:05:07 INFO - 'TMOUT': '86400',
13:05:07 INFO - 'USER': 'cltbld',
13:05:07 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:07 INFO - '_': '/tools/buildbot/bin/python'}
13:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:07 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))
13:05:07 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))
13:05:07 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))
13:05:07 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))
13:05:07 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))
13:05:07 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))
13:05:07 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))
13:05:07 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))
13:05:07 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))
13:05:07 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
13:05:08 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:05:10 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
13:05:10 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))
13:05:10 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))
13:05:10 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))
13:05:10 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))
13:05:11 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
13:05:11 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:05:12 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
13:05:12 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:05:13 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
13:05:13 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
13:05:14 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
13:05:14 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
13:05:14 INFO - Found existing installation: requests 1.2.3
13:05:14 INFO - Uninstalling requests-1.2.3:
13:05:14 INFO - Successfully uninstalled requests-1.2.3
13:05:14 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
13:05:14 INFO - Return code: 0
13:05:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
13:05: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')]}
13:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
13:05: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')]}
13:05:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
13:05:14 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 0x24aed50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x248da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2631460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563', '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
13:05:14 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
13:05:14 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
13:05:14 INFO - Using env: {'DISPLAY': ':0',
13:05:14 INFO - 'HOME': '/home/cltbld',
13:05:14 INFO - 'LANG': 'en_US.UTF-8',
13:05:14 INFO - 'LANGUAGE': 'en_US:en',
13:05:14 INFO - 'LOGNAME': 'cltbld',
13:05:14 INFO - 'MAIL': '/var/mail/cltbld',
13:05:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:14 INFO - 'MOZ_NO_REMOTE': '1',
13:05:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:14 INFO - 'NO_EM_RESTART': '1',
13:05:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:14 INFO - 'PWD': '/builds/slave/test',
13:05:14 INFO - 'SHELL': '/bin/bash',
13:05:14 INFO - 'SHLVL': '1',
13:05:14 INFO - 'TERM': 'linux',
13:05:14 INFO - 'TMOUT': '86400',
13:05:14 INFO - 'USER': 'cltbld',
13:05:14 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:05:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:14 INFO - '_': '/tools/buildbot/bin/python'}
13:05:15 INFO - Ignoring indexes: https://pypi.python.org/simple
13:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
13:05:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
13:05:15 INFO - Return code: 0
13:05:15 INFO - Running post-action listener: _resource_record_post_action
13:05:15 INFO - Running post-action listener: _start_resource_monitoring
13:05:15 INFO - Starting resource monitoring.
13:05:15 INFO - [mozharness: 2017-11-17 21:05:15.289421Z] Finished create-virtualenv step (success)
13:05:15 INFO - [mozharness: 2017-11-17 21:05:15.290001Z] Running install step.
13:05:15 INFO - Running pre-action listener: _resource_record_pre_action
13:05:15 INFO - Running main action method: install
13:05:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
13:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
13:05:15 INFO - Reading from file tmpfile_stdout
13:05:15 INFO - Reading from file tmpfile_stderr
13:05:15 INFO - Detecting whether we're running mozinstall >=1.0...
13:05:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
13:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
13:05:16 INFO - Reading from file tmpfile_stdout
13:05:16 INFO - Output received:
13:05:16 INFO - Usage: mozinstall [options] installer
13:05:16 INFO - Options:
13:05:16 INFO - -h, --help show this help message and exit
13:05:16 INFO - -d DEST, --destination=DEST
13:05:16 INFO - Directory to install application into. [default:
13:05:16 INFO - "/builds/slave/test"]
13:05:16 INFO - --app=APP Application being installed. [default: firefox]
13:05:16 INFO - mkdir: /builds/slave/test/build/application
13:05:16 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']
13:05:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
13:05:34 INFO - Reading from file tmpfile_stdout
13:05:34 INFO - Output received:
13:05:34 INFO - /builds/slave/test/build/application/firefox/firefox
13:05:34 INFO - Running post-action listener: _resource_record_post_action
13:05:34 INFO - [mozharness: 2017-11-17 21:05:34.186541Z] Finished install step (success)
13:05:34 INFO - [mozharness: 2017-11-17 21:05:34.186788Z] Running setup-mitmproxy step.
13:05:34 INFO - Running pre-action listener: _resource_record_pre_action
13:05:34 INFO - Running main action method: setup_mitmproxy
13:05:34 INFO - Skipping: mitmproxy is not required
13:05:34 INFO - Running post-action listener: _resource_record_post_action
13:05:34 INFO - [mozharness: 2017-11-17 21:05:34.187903Z] Finished setup-mitmproxy step (success)
13:05:34 INFO - [mozharness: 2017-11-17 21:05:34.188120Z] Running run-tests step.
13:05:34 INFO - Running pre-action listener: _resource_record_pre_action
13:05:34 INFO - Running pre-action listener: _set_gcov_prefix
13:05:34 INFO - Running main action method: run_tests
13:05:34 WARNING - Try message not found.
13:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
13:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
13:05:34 INFO - Python 2.7.3
13:05:34 INFO - Return code: 0
13:05:34 INFO - grabbing minidump binary from tooltool
13:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
13:05:34 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 0x262e600>, '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 0x262f500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x262f990>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
13:05:34 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
13:05:34 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
13:05:34 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
13:05:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
13:05:34 INFO - Return code: 0
13:05:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
13:05:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
13:05:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
13:05:34 INFO - ENV: RUST_BACKTRACE is now full
13:05:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
13:05:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
13:05:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build
13:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log
13:05:34 INFO - Using env: {'DISPLAY': ':0',
13:05:34 INFO - 'HOME': '/home/cltbld',
13:05:34 INFO - 'LANG': 'en_US.UTF-8',
13:05:34 INFO - 'LANGUAGE': 'en_US:en',
13:05:34 INFO - 'LOGNAME': 'cltbld',
13:05:34 INFO - 'MAIL': '/var/mail/cltbld',
13:05:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:05:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:05:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:34 INFO - 'MOZ_NO_REMOTE': '1',
13:05:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:05:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:34 INFO - 'NO_EM_RESTART': '1',
13:05:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:34 INFO - 'PWD': '/builds/slave/test',
13:05:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:05:34 INFO - 'RUST_BACKTRACE': 'full',
13:05:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:05:34 INFO - 'SHELL': '/bin/bash',
13:05:34 INFO - 'SHLVL': '1',
13:05:34 INFO - 'TERM': 'linux',
13:05:34 INFO - 'TMOUT': '86400',
13:05:34 INFO - 'USER': 'cltbld',
13:05:34 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:05:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:34 INFO - '_': '/tools/buildbot/bin/python'}
13:05:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] with output_timeout 3600
13:05:34 INFO - mozversion application_buildid: 20171117194157
13:05:34 INFO - mozversion application_changeset: 36cad9c45551e29cfb09dd1238c3f56d52776abf
13:05:34 INFO - mozversion application_display_name: Nightly
13:05:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
13:05:34 INFO - mozversion application_name: Firefox
13:05:34 INFO - mozversion application_remotingname: firefox
13:05:34 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
13:05:34 INFO - mozversion application_vendor: Mozilla
13:05:34 INFO - mozversion application_version: 59.0a1
13:05:34 INFO - mozversion platform_buildid: 20171117194157
13:05:34 INFO - mozversion platform_changeset: 36cad9c45551e29cfb09dd1238c3f56d52776abf
13:05:34 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
13:05:34 INFO - mozversion platform_version: 59.0a1
13:05:34 INFO - using testdate: 1510952734
13:05:34 INFO - actual date: 1510952734
13:05:34 INFO - starting webserver on 'localhost:46436'
13:05:34 INFO - SUITE-START | Running 2 tests
13:05:34 INFO - TEST-START | basic_compositor_video
13:05:34 INFO - Initialising browser for basic_compositor_video test...
13:05:34 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:05:34 INFO - Installing Add-ons
13:05:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46436/getInfo.html -profile /tmp/tmp8MahBz/profile
13:05:34 INFO - TEST-INFO | started process 3742 (/builds/slave/test/build/application/firefox/firefox http://localhost:46436/getInfo.html)
13:05:42 INFO - TEST-INFO | 3742: exit 0
13:05:42 INFO - Browser initialized.
13:05:42 INFO - Running cycle 1/1 for basic_compositor_video test...
13:05:42 INFO - Using env: {'DISPLAY': ':0',
13:05:42 INFO - 'HOME': '/home/cltbld',
13:05:42 INFO - 'JSGC_DISABLE_POISONING': '1',
13:05:42 INFO - 'LANG': 'en_US.UTF-8',
13:05:42 INFO - 'LANGUAGE': 'en_US:en',
13:05:42 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:05:42 INFO - 'LOGNAME': 'cltbld',
13:05:42 INFO - 'MAIL': '/var/mail/cltbld',
13:05:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:05:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:05:42 INFO - 'MOZ_CRASHREPORTER': '1',
13:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:05:42 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:05:42 INFO - 'MOZ_NO_REMOTE': '1',
13:05:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:05:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:05:42 INFO - 'NO_EM_RESTART': '1',
13:05:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:05:42 INFO - 'PWD': '/builds/slave/test',
13:05:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:05:42 INFO - 'RUST_BACKTRACE': 'full',
13:05:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:05:42 INFO - 'SHELL': '/bin/bash',
13:05:42 INFO - 'SHLVL': '1',
13:05:42 INFO - 'STYLO_FORCE_DISABLED': '1',
13:05:42 INFO - 'TERM': 'linux',
13:05:42 INFO - 'TMOUT': '86400',
13:05:42 INFO - 'USER': 'cltbld',
13:05:42 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:05:42 INFO - '_': '/tools/buildbot/bin/python'}
13:05:42 INFO - TEST-INFO | started process 3977 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8MahBz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
13:05:43 INFO - PID 3977 |
13:05:43 INFO - PID 3977 | (/builds/slave/test/build/application/firefox/firefox:4029): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:05:43 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838664421997755 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6656702747710244 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.709498575498576 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7007695578231288 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.12742090784044 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.117510288065841 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.5429693034238516 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.468084922010397 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672060491493385 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5422022160664834 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.311391752577325 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.005074999999998 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.871811594202894 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.584259259259275 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397747933884286 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.979730538922144 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542749999999993 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87263586956523 ms/frame
13:06:29 INFO - PID 3977 |
13:06:29 INFO - PID 3977 | Cycle 1(1): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:07:15 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.690805633802817 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668315262718932 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.726415420023015 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.701509353741497 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.027744966442953 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.142515527950309 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.6593780487804906 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5666399286987494 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704515209125477 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.60415966386555 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.313608247422675 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.753548387096773 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991135531135525 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.474240837696344 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.66168776371308 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05433734939757 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.27934210526316 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.810837837837862 ms/frame
13:07:15 INFO - PID 3977 |
13:07:15 INFO - PID 3977 | Cycle 1(2): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:08:01 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.68573595505618 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668673894912427 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.68785151856018 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6927749576988154 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.249872521246458 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.211399999999998 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.493544819557625 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4196837606837596 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.641118421052633 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510303030303035 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.753888888888898 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 9.710024271844658 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.321716981132079 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.586269841269843 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399607438016536 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.834378698224864 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.629593023255804 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.933005464480903 ms/frame
13:08:01 INFO - PID 3977 |
13:08:01 INFO - PID 3977 | Cycle 1(3): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:08:48 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.684758562605278 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6654829308909245 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.724413793103448 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7057544757033252 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8970000000000007 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.177223382045928 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.5260223266744988 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4440877796901948 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672911153119088 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.479616438356168 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.036237458193977 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.106590909090906 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.032481617647068 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639095744680859 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.40035123966943 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.051445783132491 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.32235849056605 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.753682795698912 ms/frame
13:08:48 INFO - PID 3977 |
13:08:48 INFO - PID 3977 | Cycle 1(4): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:09:34 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6913275084554682 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698163606010012 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.724735632183908 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6922588832487302 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.023063002680965 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.025603621730383 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.610824308062577 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5591903914590737 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.726498091603056 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620800561797756 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.239726962457343 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.207295918367334 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.112444444444433 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.53015789473686 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.401033057851222 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766588235294115 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586872586872587 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.114166666666643 ms/frame
13:09:34 INFO - PID 3977 |
13:09:34 INFO - PID 3977 | Cycle 1(5): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:10:20 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.677168809390721 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.675938023450586 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7285829493087557 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6838215488215489 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.232327221438645 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.125659793814433 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.476714947856316 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.442925989672978 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.618735955056184 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.436739130434776 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.204455782312927 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 9.760634146341465 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.23784644194757 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.638749999999995 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500208333333346 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.905000000000005 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.40846007604561 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.934180327868862 ms/frame
13:10:20 INFO - PID 3977 |
13:10:20 INFO - PID 3977 | Cycle 1(6): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:11:06 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6773784237003915 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684945788156795 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7544766081871348 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7144215938303347 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.190900837988826 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.184769874476987 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.708683559950557 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.61791139240506 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7252290076335886 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.527803867403311 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.275993150684933 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.053618090452252 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991446886446889 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.472905759162288 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.606344537815117 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.196646341463415 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.238071161048671 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.81110810810811 ms/frame
13:11:06 INFO - PID 3977 |
13:11:06 INFO - PID 3977 | Cycle 1(7): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:11:52 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827846326416154 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673708333333335 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7167534324942793 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.68428872053872 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.126671251719393 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.868665377176016 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.525575793184488 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.45471502590674 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.661943396226419 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.482150684931498 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.384411764705886 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.312551546391745 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.949562043795622 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.638909574468075 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.195569105691058 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.696783625731003 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.538615384615388 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.993021978021956 ms/frame
13:11:52 INFO - PID 3977 |
13:11:52 INFO - PID 3977 | Cycle 1(8): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:12:38 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6770626048071549 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6695575959933227 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.743680999418943 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7029702127659565 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1681180555555555 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.195429769392036 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.476697566628041 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4736111111111097 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.557111111111111 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.48080821917808 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.274863013698624 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.256538461538469 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.199440298507465 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.758225806451607 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.553451882845163 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766735294117654 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.154869888475849 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.699064171122988 ms/frame
13:12:38 INFO - PID 3977 |
13:12:38 INFO - PID 3977 | Cycle 1(9): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:13:25 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686787521079258 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6709983291562243 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7275705238917674 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.692910321489002 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 3.969411375661376 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.202058823529413 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.5051927570093455 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.391677966101695 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682973484848486 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618258426966288 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 9.935662251655629 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 9.710509708737854 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.83453068592058 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.526789473684191 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296249999999985 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.836360946745568 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.322245283018884 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932076502732238 ms/frame
13:13:25 INFO - PID 3977 |
13:13:25 INFO - PID 3977 | Cycle 1(10): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:14:11 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6829500841278746 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6731856187290968 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7175329135661137 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6939077053344624 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0609404600811905 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.12405154639175 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.62405193236715 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5405486725663713 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640122180451131 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.480506849315064 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.102912457912453 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 10.054221105527642 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.912490909090927 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.416770833333317 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660105485232066 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.834911242603543 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.40979087452472 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.872527173913033 ms/frame
13:14:11 INFO - PID 3977 |
13:14:11 INFO - PID 3977 | Cycle 1(11): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:14:57 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6779418344519015 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669791666666667 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7245172413793108 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7155188679245277 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_startup = 4.14418508287293 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.184895397489538 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_startup = 3.6103610108303283 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1_inclip = 3.472482638888885 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.526104972375689 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.619311797752805 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_startup = 10.068825503355699 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.480p.60fps.webm_scale_2_inclip = 9.666159420289857 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991190476190468 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.585476190476184 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.502020833333336 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052891566265053 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.281146616541367 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932677595628418 ms/frame
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | Cycle 1(12): loaded http://localhost:46436/tests/video/video_playback.html (next: http://localhost:46436/tests/video/video_playback.html)
13:14:57 INFO - PID 3977 | __start_tp_report
13:14:57 INFO - PID 3977 | _x_x_mozilla_page_load
13:14:57 INFO - PID 3977 | _x_x_mozilla_page_load_details
13:14:57 INFO - PID 3977 | |i|pagename|runs|
13:14:57 INFO - PID 3977 | |0;240p.120fps.mp4_scale_1_startup;1.6838664421997755;1.690805633802817;1.68573595505618;1.684758562605278;1.6913275084554682;1.677168809390721;1.6773784237003915;1.6827846326416154;1.6770626048071549;1.686787521079258;1.6829500841278746;1.6779418344519015
13:14:57 INFO - PID 3977 | |1;240p.120fps.mp4_scale_1_inclip;1.6656702747710244;1.668315262718932;1.668673894912427;1.6654829308909245;1.6698163606010012;1.675938023450586;1.6684945788156795;1.6673708333333335;1.6695575959933227;1.6709983291562243;1.6731856187290968;1.6669791666666667
13:14:57 INFO - PID 3977 | |2;240p.120fps.mp4_scale_1.1_startup;1.709498575498576;1.726415420023015;1.68785151856018;1.724413793103448;1.724735632183908;1.7285829493087557;1.7544766081871348;1.7167534324942793;1.743680999418943;1.7275705238917674;1.7175329135661137;1.7245172413793108
13:14:57 INFO - PID 3977 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7007695578231288;1.701509353741497;1.6927749576988154;1.7057544757033252;1.6922588832487302;1.6838215488215489;1.7144215938303347;1.68428872053872;1.7029702127659565;1.692910321489002;1.6939077053344624;1.7155188679245277
13:14:57 INFO - PID 3977 | |4;240p.120fps.mp4_scale_2_startup;4.12742090784044;4.027744966442953;4.249872521246458;3.8970000000000007;4.023063002680965;4.232327221438645;4.190900837988826;4.126671251719393;4.1681180555555555;3.969411375661376;4.0609404600811905;4.14418508287293
13:14:57 INFO - PID 3977 | |5;240p.120fps.mp4_scale_2_inclip;4.117510288065841;4.142515527950309;4.211399999999998;4.177223382045928;4.025603621730383;4.125659793814433;4.184769874476987;3.868665377176016;4.195429769392036;4.202058823529413;4.12405154639175;4.184895397489538
13:14:57 INFO - PID 3977 | |6;480p.60fps.webm_scale_1_startup;3.5429693034238516;3.6593780487804906;3.493544819557625;3.5260223266744988;3.610824308062577;3.476714947856316;3.708683559950557;3.525575793184488;3.476697566628041;3.5051927570093455;3.62405193236715;3.6103610108303283
13:14:57 INFO - PID 3977 | |7;480p.60fps.webm_scale_1_inclip;3.468084922010397;3.5666399286987494;3.4196837606837596;3.4440877796901948;3.5591903914590737;3.442925989672978;3.61791139240506;3.45471502590674;3.4736111111111097;3.391677966101695;3.5405486725663713;3.472482638888885
13:14:57 INFO - PID 3977 | |8;480p.60fps.webm_scale_1.1_startup;5.672060491493385;5.704515209125477;5.641118421052633;5.672911153119088;5.726498091603056;5.618735955056184;5.7252290076335886;5.661943396226419;5.557111111111111;5.682973484848486;5.640122180451131;5.526104972375689
13:14:57 INFO - PID 3977 | |9;480p.60fps.webm_scale_1.1_inclip;5.5422022160664834;5.60415966386555;5.510303030303035;5.479616438356168;5.620800561797756;5.436739130434776;5.527803867403311;5.482150684931498;5.48080821917808;5.618258426966288;5.480506849315064;5.619311797752805
13:14:57 INFO - PID 3977 | |10;480p.60fps.webm_scale_2_startup;10.311391752577325;10.313608247422675;10.753888888888898;10.036237458193977;10.239726962457343;10.204455782312927;10.275993150684933;10.384411764705886;10.274863013698624;9.935662251655629;10.102912457912453;10.068825503355699
13:14:57 INFO - PID 3977 | |11;480p.60fps.webm_scale_2_inclip;10.005074999999998;10.753548387096773;9.710024271844658;10.106590909090906;10.207295918367334;9.760634146341465;10.053618090452252;10.312551546391745;10.256538461538469;9.710509708737854;10.054221105527642;9.666159420289857
13:14:57 INFO - PID 3977 | |12;1080p.60fps.mp4_scale_1_startup;10.871811594202894;10.991135531135525;11.321716981132079;11.032481617647068;11.112444444444433;11.23784644194757;10.991446886446889;10.949562043795622;11.199440298507465;10.83453068592058;10.912490909090927;10.991190476190468
13:14:57 INFO - PID 3977 | |13;1080p.60fps.mp4_scale_1_inclip;10.584259259259275;10.474240837696344;10.586269841269843;10.639095744680859;10.53015789473686;10.638749999999995;10.472905759162288;10.638909574468075;10.758225806451607;10.526789473684191;10.416770833333317;10.585476190476184
13:14:57 INFO - PID 3977 | |14;1080p.60fps.mp4_scale_1.1_startup;12.397747933884286;12.66168776371308;12.399607438016536;12.40035123966943;12.401033057851222;12.500208333333346;12.606344537815117;12.195569105691058;12.553451882845163;12.296249999999985;12.660105485232066;12.502020833333336
13:14:57 INFO - PID 3977 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.979730538922144;12.05433734939757;11.834378698224864;12.051445783132491;11.766588235294115;11.905000000000005;12.196646341463415;11.696783625731003;11.766735294117654;11.836360946745568;11.834911242603543;12.052891566265053
13:14:57 INFO - PID 3977 | |16;1080p.60fps.mp4_scale_2_startup;11.542749999999993;11.27934210526316;11.629593023255804;11.32235849056605;11.586872586872587;11.40846007604561;11.238071161048671;11.538615384615388;11.154869888475849;11.322245283018884;11.40979087452472;11.281146616541367
13:14:57 INFO - PID 3977 | |17;1080p.60fps.mp4_scale_2_inclip;10.87263586956523;10.810837837837862;10.933005464480903;10.753682795698912;11.114166666666643;10.934180327868862;10.81110810810811;10.993021978021956;10.699064171122988;10.932076502732238;10.872527173913033;10.932677595628418
13:14:57 INFO - PID 3977 | __end_tp_report
13:14:57 INFO - PID 3977 | __start_cc_report
13:14:57 INFO - PID 3977 | _x_x_mozilla_cycle_collect,545
13:14:57 INFO - PID 3977 | __end_cc_report
13:14:57 INFO - PID 3977 | __startTimestamp1510953297662__endTimestamp
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | ------- Summary: start -------
13:14:57 INFO - PID 3977 | Number of tests: 18
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.01 (0.3%) stddev-sans-first:0.01
13:14:57 INFO - PID 3977 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.2%) stddev-sans-first:0.00
13:14:57 INFO - PID 3977 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.72 Median:1.73 stddev:0.02 (1.0%) stddev-sans-first:0.02
13:14:57 INFO - PID 3977 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.70 Median:1.70 stddev:0.01 (0.6%) stddev-sans-first:0.01
13:14:57 INFO - PID 3977 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.10 Median:4.14 stddev:0.11 (2.6%) stddev-sans-first:0.11
13:14:57 INFO - PID 3977 | Values: 4.1 4.0 4.2 3.9 4.0 4.2 4.2 4.1 4.2 4.0 4.1 4.1
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.13 Median:4.18 stddev:0.10 (2.3%) stddev-sans-first:0.10
13:14:57 INFO - PID 3977 | Values: 4.1 4.1 4.2 4.2 4.0 4.1 4.2 3.9 4.2 4.2 4.1 4.2
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.56 Median:3.58 stddev:0.08 (2.1%) stddev-sans-first:0.08
13:14:57 INFO - PID 3977 | Values: 3.5 3.7 3.5 3.5 3.6 3.5 3.7 3.5 3.5 3.5 3.6 3.6
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.49 Median:3.47 stddev:0.07 (2.0%) stddev-sans-first:0.07
13:14:57 INFO - PID 3977 | Values: 3.5 3.6 3.4 3.4 3.6 3.4 3.6 3.5 3.5 3.4 3.5 3.5
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.65 Median:5.67 stddev:0.06 (1.1%) stddev-sans-first:0.06
13:14:57 INFO - PID 3977 | Values: 5.7 5.7 5.6 5.7 5.7 5.6 5.7 5.7 5.6 5.7 5.6 5.5
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.53 Median:5.54 stddev:0.07 (1.2%) stddev-sans-first:0.07
13:14:57 INFO - PID 3977 | Values: 5.5 5.6 5.5 5.5 5.6 5.4 5.5 5.5 5.5 5.6 5.5 5.6
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.24 Median:10.29 stddev:0.21 (2.0%) stddev-sans-first:0.22
13:14:57 INFO - PID 3977 | Values: 10.3 10.3 10.8 10.0 10.2 10.2 10.3 10.4 10.3 9.9 10.1 10.1
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.05 Median:10.53 stddev:0.32 (3.0%) stddev-sans-first:0.33
13:14:57 INFO - PID 3977 | Values: 10.0 10.8 9.7 10.1 10.2 9.8 10.1 10.3 10.3 9.7 10.1 9.7
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.04 Median:11.01 stddev:0.15 (1.4%) stddev-sans-first:0.15
13:14:57 INFO - PID 3977 | Values: 10.9 11.0 11.3 11.0 11.1 11.2 11.0 10.9 11.2 10.8 10.9 11.0
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.57 Median:10.59 stddev:0.09 (0.9%) stddev-sans-first:0.10
13:14:57 INFO - PID 3977 | Values: 10.6 10.5 10.6 10.6 10.5 10.6 10.5 10.6 10.8 10.5 10.4 10.6
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.46 Median:12.50 stddev:0.14 (1.1%) stddev-sans-first:0.15
13:14:57 INFO - PID 3977 | Values: 12.4 12.7 12.4 12.4 12.4 12.5 12.6 12.2 12.6 12.3 12.7 12.5
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.91 Median:11.94 stddev:0.15 (1.3%) stddev-sans-first:0.16
13:14:57 INFO - PID 3977 | Values: 12.0 12.1 11.8 12.1 11.8 11.9 12.2 11.7 11.8 11.8 11.8 12.1
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.39 Median:11.41 stddev:0.15 (1.3%) stddev-sans-first:0.15
13:14:57 INFO - PID 3977 | Values: 11.5 11.3 11.6 11.3 11.6 11.4 11.2 11.5 11.2 11.3 11.4 11.3
13:14:57 INFO - PID 3977 |
13:14:57 INFO - PID 3977 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.89 Median:10.93 stddev:0.11 (1.0%) stddev-sans-first:0.12
13:14:57 INFO - PID 3977 | Values: 10.9 10.8 10.9 10.8 11.1 10.9 10.8 11.0 10.7 10.9 10.9 10.9
13:14:57 INFO - PID 3977 | -------- Summary: end --------
13:14:57 INFO - PID 3977 |
13:14:58 INFO - TEST-INFO | 3977: exit 0
13:14:58 INFO - TEST-OK | basic_compositor_video | took 563457ms
13:14:58 INFO - TEST-START | glvideo
13:14:58 INFO - Initialising browser for glvideo test...
13:14:58 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
13:14:58 INFO - Installing Add-ons
13:14:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46436/getInfo.html -profile /tmp/tmp6nMScs/profile
13:14:58 INFO - TEST-INFO | started process 5209 (/builds/slave/test/build/application/firefox/firefox http://localhost:46436/getInfo.html)
13:15:04 INFO - TEST-INFO | 5209: exit 0
13:15:04 INFO - Browser initialized.
13:15:04 INFO - Running cycle 1/1 for glvideo test...
13:15:04 INFO - Using env: {'DISPLAY': ':0',
13:15:04 INFO - 'HOME': '/home/cltbld',
13:15:04 INFO - 'JSGC_DISABLE_POISONING': '1',
13:15:04 INFO - 'LANG': 'en_US.UTF-8',
13:15:04 INFO - 'LANGUAGE': 'en_US:en',
13:15:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
13:15:04 INFO - 'LOGNAME': 'cltbld',
13:15:04 INFO - 'MAIL': '/var/mail/cltbld',
13:15:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
13:15:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
13:15:04 INFO - 'MOZ_CRASHREPORTER': '1',
13:15:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
13:15:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
13:15:04 INFO - 'MOZ_NO_REMOTE': '1',
13:15:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
13:15:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
13:15:04 INFO - 'NO_EM_RESTART': '1',
13:15:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
13:15:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
13:15:04 INFO - 'PWD': '/builds/slave/test',
13:15:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
13:15:04 INFO - 'RUST_BACKTRACE': 'full',
13:15:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
13:15:04 INFO - 'SHELL': '/bin/bash',
13:15:04 INFO - 'SHLVL': '1',
13:15:04 INFO - 'STYLO_FORCE_DISABLED': '1',
13:15:04 INFO - 'TERM': 'linux',
13:15:04 INFO - 'TMOUT': '86400',
13:15:04 INFO - 'USER': 'cltbld',
13:15:04 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563',
13:15:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
13:15:04 INFO - '_': '/tools/buildbot/bin/python'}
13:15:04 INFO - TEST-INFO | started process 5483 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6nMScs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
13:15:04 INFO - PID 5483 |
13:15:04 INFO - PID 5483 | (/builds/slave/test/build/application/firefox/firefox:5536): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
13:15:04 INFO - PID 5483 |
13:15:06 INFO - PID 5483 | [talos glvideo result] Mean tick time across 100 ticks: 7.379849999999999 ms
13:15:06 INFO - PID 5483 | Cycle 1(1): loaded http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html)
13:15:07 INFO - PID 5483 | [talos glvideo result] Mean tick time across 100 ticks: 6.4782 ms
13:15:07 INFO - PID 5483 | Cycle 1(2): loaded http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html)
13:15:09 INFO - PID 5483 | [talos glvideo result] Mean tick time across 100 ticks: 6.60425 ms
13:15:09 INFO - PID 5483 | Cycle 1(3): loaded http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html)
13:15:10 INFO - PID 5483 | [talos glvideo result] Mean tick time across 100 ticks: 6.613300000000001 ms
13:15:10 INFO - PID 5483 | Cycle 1(4): loaded http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html)
13:15:11 INFO - PID 5483 | [talos glvideo result] Mean tick time across 100 ticks: 6.65245 ms
13:15:11 INFO - PID 5483 | Cycle 1(5): loaded http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46436/tests/webgl/benchmarks/video/video_upload.html)
13:15:12 INFO - PID 5483 | __start_tp_report
13:15:12 INFO - PID 5483 | _x_x_mozilla_page_load
13:15:12 INFO - PID 5483 | _x_x_mozilla_page_load_details
13:15:12 INFO - PID 5483 | |i|pagename|runs|
13:15:12 INFO - PID 5483 | |0;Mean tick time across 100 ticks: ;7.379849999999999;6.4782;6.60425;6.613300000000001;6.65245
13:15:12 INFO - PID 5483 | __end_tp_report
13:15:12 INFO - PID 5483 | __start_cc_report
13:15:12 INFO - PID 5483 | _x_x_mozilla_cycle_collect,284
13:15:12 INFO - PID 5483 | __end_cc_report
13:15:12 INFO - PID 5483 | __startTimestamp1510953312048__endTimestamp
13:15:12 INFO - PID 5483 |
13:15:12 INFO - PID 5483 | ------- Summary: start -------
13:15:12 INFO - PID 5483 | Number of tests: 1
13:15:12 INFO - PID 5483 |
13:15:12 INFO - PID 5483 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.75 Median:6.61 stddev:0.36 (5.5%) stddev-sans-first:0.08
13:15:12 INFO - PID 5483 | Values: 7.4 6.5 6.6 6.6 6.7
13:15:12 INFO - PID 5483 | -------- Summary: end --------
13:15:12 INFO - PID 5483 |
13:15:12 INFO - PID 5483 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
13:15:12 INFO - TEST-INFO | 5483: exit 0
13:15:12 INFO - TEST-OK | glvideo | took 14470ms
13:15:12 INFO - SUITE-END | took 577s
13:15:13 INFO - Completed test suite (00:09:38)
13:15:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.5827030025802395, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6838664421997755, 1.690805633802817, 1.68573595505618, 1.684758562605278, 1.6913275084554682, 1.677168809390721, 1.6773784237003915, 1.6827846326416154, 1.6770626048071549, 1.686787521079258, 1.6829500841278746, 1.6779418344519015], "value": 1.6829500841278746, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6656702747710244, 1.668315262718932, 1.668673894912427, 1.6654829308909245, 1.6698163606010012, 1.675938023450586, 1.6684945788156795, 1.6673708333333335, 1.6695575959933227, 1.6709983291562243, 1.6731856187290968, 1.6669791666666667], "value": 1.668673894912427, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.709498575498576, 1.726415420023015, 1.68785151856018, 1.724413793103448, 1.724735632183908, 1.7285829493087557, 1.7544766081871348, 1.7167534324942793, 1.743680999418943, 1.7275705238917674, 1.7175329135661137, 1.7245172413793108], "value": 1.724735632183908, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7007695578231288, 1.701509353741497, 1.6927749576988154, 1.7057544757033252, 1.6922588832487302, 1.6838215488215489, 1.7144215938303347, 1.68428872053872, 1.7029702127659565, 1.692910321489002, 1.6939077053344624, 1.7155188679245277], "value": 1.6939077053344624, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.12742090784044, 4.027744966442953, 4.249872521246458, 3.8970000000000007, 4.023063002680965, 4.232327221438645, 4.190900837988826, 4.126671251719393, 4.1681180555555555, 3.969411375661376, 4.0609404600811905, 4.14418508287293], "value": 4.126671251719393, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.117510288065841, 4.142515527950309, 4.211399999999998, 4.177223382045928, 4.025603621730383, 4.125659793814433, 4.184769874476987, 3.868665377176016, 4.195429769392036, 4.202058823529413, 4.12405154639175, 4.184895397489538], "value": 4.177223382045928, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5429693034238516, 3.6593780487804906, 3.493544819557625, 3.5260223266744988, 3.610824308062577, 3.476714947856316, 3.708683559950557, 3.525575793184488, 3.476697566628041, 3.5051927570093455, 3.62405193236715, 3.6103610108303283], "value": 3.5260223266744988, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.468084922010397, 3.5666399286987494, 3.4196837606837596, 3.4440877796901948, 3.5591903914590737, 3.442925989672978, 3.61791139240506, 3.45471502590674, 3.4736111111111097, 3.391677966101695, 3.5405486725663713, 3.472482638888885], "value": 3.472482638888885, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.672060491493385, 5.704515209125477, 5.641118421052633, 5.672911153119088, 5.726498091603056, 5.618735955056184, 5.7252290076335886, 5.661943396226419, 5.557111111111111, 5.682973484848486, 5.640122180451131, 5.526104972375689], "value": 5.661943396226419, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.5422022160664834, 5.60415966386555, 5.510303030303035, 5.479616438356168, 5.620800561797756, 5.436739130434776, 5.527803867403311, 5.482150684931498, 5.48080821917808, 5.618258426966288, 5.480506849315064, 5.619311797752805], "value": 5.510303030303035, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.311391752577325, 10.313608247422675, 10.753888888888898, 10.036237458193977, 10.239726962457343, 10.204455782312927, 10.275993150684933, 10.384411764705886, 10.274863013698624, 9.935662251655629, 10.102912457912453, 10.068825503355699], "value": 10.239726962457343, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.005074999999998, 10.753548387096773, 9.710024271844658, 10.106590909090906, 10.207295918367334, 9.760634146341465, 10.053618090452252, 10.312551546391745, 10.256538461538469, 9.710509708737854, 10.054221105527642, 9.666159420289857], "value": 10.054221105527642, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.871811594202894, 10.991135531135525, 11.321716981132079, 11.032481617647068, 11.112444444444433, 11.23784644194757, 10.991446886446889, 10.949562043795622, 11.199440298507465, 10.83453068592058, 10.912490909090927, 10.991190476190468], "value": 10.991446886446889, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.584259259259275, 10.474240837696344, 10.586269841269843, 10.639095744680859, 10.53015789473686, 10.638749999999995, 10.472905759162288, 10.638909574468075, 10.758225806451607, 10.526789473684191, 10.416770833333317, 10.585476190476184], "value": 10.585476190476184, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.397747933884286, 12.66168776371308, 12.399607438016536, 12.40035123966943, 12.401033057851222, 12.500208333333346, 12.606344537815117, 12.195569105691058, 12.553451882845163, 12.296249999999985, 12.660105485232066, 12.502020833333336], "value": 12.500208333333346, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.979730538922144, 12.05433734939757, 11.834378698224864, 12.051445783132491, 11.766588235294115, 11.905000000000005, 12.196646341463415, 11.696783625731003, 11.766735294117654, 11.836360946745568, 11.834911242603543, 12.052891566265053], "value": 11.836360946745568, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.542749999999993, 11.27934210526316, 11.629593023255804, 11.32235849056605, 11.586872586872587, 11.40846007604561, 11.238071161048671, 11.538615384615388, 11.154869888475849, 11.322245283018884, 11.40979087452472, 11.281146616541367], "value": 11.32235849056605, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.87263586956523, 10.810837837837862, 10.933005464480903, 10.753682795698912, 11.114166666666643, 10.934180327868862, 10.81110810810811, 10.993021978021956, 10.699064171122988, 10.932076502732238, 10.872527173913033, 10.932677595628418], "value": 10.932076502732238, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.379849999999999, 6.4782, 6.60425, 6.613300000000001, 6.65245], "value": 6.6087750000000005, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
13:15:13 INFO - Return code: 0
13:15:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
13:15:13 INFO - # TBPL SUCCESS #
13:15:13 INFO - Running post-action listener: _package_coverage_data
13:15:13 INFO - Running post-action listener: _resource_record_post_action
13:15:13 INFO - [mozharness: 2017-11-17 21:15:13.220158Z] Finished run-tests step (success)
13:15:13 INFO - Running post-run listener: _resource_record_post_run
13:15:13 INFO - Total resource usage - Wall time: 598s; CPU: 38.0%; Read bytes: 17809408; Write bytes: 312795136; Read time: 3496; Write time: 240272
13:15:13 INFO - TinderboxPrint: CPU usage
38.4%
13:15:13 INFO - TinderboxPrint: I/O read bytes / time
17,809,408 / 3,496
13:15:13 INFO - TinderboxPrint: I/O write bytes / time
312,795,136 / 240,272
13:15:13 INFO - TinderboxPrint: CPU idle
2,888.7 (61.6%)
13:15:13 INFO - TinderboxPrint: CPU system
306.6 (6.5%)
13:15:13 INFO - TinderboxPrint: CPU user
1,476.8 (31.5%)
13:15:13 INFO - TinderboxPrint: Swap in / out
0 / 0
13:15:13 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 8192; Write bytes: 91975680; Read time: 268; Write time: 88216
13:15:13 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
13:15:13 INFO - run-tests - Wall time: 579s; CPU: 39.0%; Read bytes: 16039936; Write bytes: 151154688; Read time: 3168; Write time: 35452
13:15:13 INFO - Running post-run listener: _upload_blobber_files
13:15:13 INFO - Blob upload gear active.
13:15:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
13:15:13 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
13:15:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
13:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
13:15:13 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:13 INFO - SNIMissingWarning
13:15:13 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:13 INFO - InsecurePlatformWarning
13:15:13 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
13:15:13 INFO - SubjectAltNameWarning
13:15:13 INFO - (blobuploader) - INFO - Open directory for files ...
13:15:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
13:15:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:15:13 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:15:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:14 INFO - InsecurePlatformWarning
13:15:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
13:15:14 INFO - SubjectAltNameWarning
13:15:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:14 INFO - InsecurePlatformWarning
13:15:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
13:15:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:15:14 INFO - (blobuploader) - INFO - Done attempting.
13:15:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
13:15:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:15:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:15:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:14 INFO - InsecurePlatformWarning
13:15:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
13:15:15 INFO - SubjectAltNameWarning
13:15:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:19 INFO - InsecurePlatformWarning
13:15:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
13:15:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:15:19 INFO - (blobuploader) - INFO - Done attempting.
13:15:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
13:15:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:15:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:15:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:19 INFO - InsecurePlatformWarning
13:15:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
13:15:19 INFO - SubjectAltNameWarning
13:15:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:20 INFO - InsecurePlatformWarning
13:15:20 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
13:15:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:15:20 INFO - (blobuploader) - INFO - Done attempting.
13:15:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
13:15:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
13:15:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
13:15:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:20 INFO - InsecurePlatformWarning
13:15:20 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
13:15:20 INFO - SubjectAltNameWarning
13:15:21 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
13:15:21 INFO - InsecurePlatformWarning
13:15:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
13:15:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
13:15:21 INFO - (blobuploader) - INFO - Done attempting.
13:15:21 INFO - (blobuploader) - INFO - Iteration through files over.
13:15:21 INFO - Return code: 0
13:15:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
13:15:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
13:15:21 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03df172e3e80e5de7940da60b4151800eba18ee539b681150339fd4878f1a6ce6820c2ca6903c6b4343d9732ea9462e8f08751ce61badc1d6ebcfed6853066a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc677a50f9820a402fabbfb7145c91968faa5c84f2774b15638d4b5727bcd9d40d3165f2c65a192307e4ff5d1911e69c8e570a237bc39b3256e493d90a8f1813", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94eac0148f1f5530378c92e95562eea40c65a5fd6798414ed66002d20202f6a0dc389badf6416b201f3806814ff5b8b6e73094e287985b54e30470d99c0a6af9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d54d37f98ad39c3eb595847f1c7ee538e2eed49a615a0bfbcffc980354cf2d5b7e3f231ed399fdb2e2a123fd7c6bdefd62b862d3984e03a8ca0570d1363616b8"}
13:15:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
13:15:21 INFO - Writing to file /builds/slave/test/properties/blobber_files
13:15:21 INFO - Contents:
13:15:21 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03df172e3e80e5de7940da60b4151800eba18ee539b681150339fd4878f1a6ce6820c2ca6903c6b4343d9732ea9462e8f08751ce61badc1d6ebcfed6853066a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc677a50f9820a402fabbfb7145c91968faa5c84f2774b15638d4b5727bcd9d40d3165f2c65a192307e4ff5d1911e69c8e570a237bc39b3256e493d90a8f1813", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94eac0148f1f5530378c92e95562eea40c65a5fd6798414ed66002d20202f6a0dc389badf6416b201f3806814ff5b8b6e73094e287985b54e30470d99c0a6af9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d54d37f98ad39c3eb595847f1c7ee538e2eed49a615a0bfbcffc980354cf2d5b7e3f231ed399fdb2e2a123fd7c6bdefd62b862d3984e03a8ca0570d1363616b8"}
13:15:21 INFO - Running post-run listener: copy_logs_to_upload_dir
13:15:21 INFO - Copying logs to upload dir...
13:15:21 INFO - mkdir: /builds/slave/test/build/upload/logs
13:15:21 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=663.040623
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 3 secs) (at 2017-11-17 13:15:21.492629) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-17 13:15:21.495800) =========
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=a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03df172e3e80e5de7940da60b4151800eba18ee539b681150339fd4878f1a6ce6820c2ca6903c6b4343d9732ea9462e8f08751ce61badc1d6ebcfed6853066a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc677a50f9820a402fabbfb7145c91968faa5c84f2774b15638d4b5727bcd9d40d3165f2c65a192307e4ff5d1911e69c8e570a237bc39b3256e493d90a8f1813", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94eac0148f1f5530378c92e95562eea40c65a5fd6798414ed66002d20202f6a0dc389badf6416b201f3806814ff5b8b6e73094e287985b54e30470d99c0a6af9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d54d37f98ad39c3eb595847f1c7ee538e2eed49a615a0bfbcffc980354cf2d5b7e3f231ed399fdb2e2a123fd7c6bdefd62b862d3984e03a8ca0570d1363616b8"}
build_url:https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011286
build_url: 'https://queue.taskcluster.net/v1/task/J1zi1dgLSe2cS-WsHejegw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/03df172e3e80e5de7940da60b4151800eba18ee539b681150339fd4878f1a6ce6820c2ca6903c6b4343d9732ea9462e8f08751ce61badc1d6ebcfed6853066a9", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dc677a50f9820a402fabbfb7145c91968faa5c84f2774b15638d4b5727bcd9d40d3165f2c65a192307e4ff5d1911e69c8e570a237bc39b3256e493d90a8f1813", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/94eac0148f1f5530378c92e95562eea40c65a5fd6798414ed66002d20202f6a0dc389badf6416b201f3806814ff5b8b6e73094e287985b54e30470d99c0a6af9", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d54d37f98ad39c3eb595847f1c7ee538e2eed49a615a0bfbcffc980354cf2d5b7e3f231ed399fdb2e2a123fd7c6bdefd62b862d3984e03a8ca0570d1363616b8"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-17 13:15:21.525473) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:15:21.525811) =========
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=a4ce256d2ec601f24a60a71e000001bc-1510952655.115555-880027563
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004528
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-17 13:15:21.610383) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-17 13:15:21.610712) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-17 13:15:21.611037) =========
========= Total master_lag: 0.22 =========