builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s
slave: talos-linux64-ix-026
starttime: 1508496028.93
results: success (0)
revision: d1e995c8640a191cd127e87273ec96cb2fabffa9
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:28.933888) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:28.939243) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:28.939680) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:29.001961) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:29.002284) =========
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=41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-20 03:40:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 227M=0s
2017-10-20 03:40:29 (227 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.375093
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:29.407240) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:29.407606) =========
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=41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.019445
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:29.463414) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-20 03:40:29.463749) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d1e995c8640a191cd127e87273ec96cb2fabffa9 --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 d1e995c8640a191cd127e87273ec96cb2fabffa9 --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=41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-20 03:40:29,519 truncating revision to first 12 chars
2017-10-20 03:40:29,520 Setting DEBUG logging.
2017-10-20 03:40:29,520 attempt 1/10
2017-10-20 03:40:29,520 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d1e995c8640a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-20 03:40:29,994 unpacking tar archive at: mozilla-central-d1e995c8640a/testing/mozharness/
program finished with exit code 0
elapsedTime=1.752191
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-20 03:40:31.238567) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:31.238875) =========
script_repo_revision: d1e995c8640a191cd127e87273ec96cb2fabffa9
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:31.239339) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:31.239641) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-20 03:40:31.256908) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 55 secs) (at 2017-10-20 03:40:31.257580) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
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=41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:40:31 INFO - MultiFileLogger online at 20171020 03:40:31 in /builds/slave/test
03:40:31 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
03:40:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:40:31 INFO - {'append_to_log': False,
03:40:31 INFO - 'base_work_dir': '/builds/slave/test',
03:40:31 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
03:40:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:40:31 INFO - 'branch': 'Firefox-Non-PGO',
03:40:31 INFO - 'buildbot_json_path': 'buildprops.json',
03:40:31 INFO - 'code_coverage': False,
03:40:31 INFO - 'config_files': ('talos/linux_config.py',),
03:40:31 INFO - 'default_actions': ('clobber',
03:40:31 INFO - 'read-buildbot-config',
03:40:31 INFO - 'download-and-extract',
03:40:31 INFO - 'populate-webroot',
03:40:31 INFO - 'create-virtualenv',
03:40:31 INFO - 'install',
03:40:31 INFO - 'setup-mitmproxy',
03:40:31 INFO - 'run-tests'),
03:40:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:40:31 INFO - 'disable_ccov_upload': False,
03:40:31 INFO - 'disable_stylo': False,
03:40:31 INFO - 'download_minidump_stackwalk': True,
03:40:31 INFO - 'download_symbols': 'ondemand',
03:40:31 INFO - 'enable_stylo': False,
03:40:31 INFO - 'enable_webrender': False,
03:40:31 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:40:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:40:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:40:31 INFO - 'gecko_profile': False,
03:40:31 INFO - 'gecko_profile_interval': 0,
03:40:31 INFO - 'installer_path': 'installer.exe',
03:40:31 INFO - 'log_level': 'info',
03:40:31 INFO - 'log_name': 'talos',
03:40:31 INFO - 'log_to_console': True,
03:40:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:40:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:40:31 INFO - 'opt_config_files': (),
03:40:31 INFO - 'pip_index': False,
03:40:31 INFO - 'suite': 'g4-stylo-disabled-e10s',
03:40:31 INFO - 'system_bits': '32',
03:40:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:40:31 INFO - 'title': 'talos-linux64-ix-026',
03:40:31 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:40:31 INFO - 'use_talos_json': True,
03:40:31 INFO - 'verify': 'False',
03:40:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:40:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:40:31 INFO - 'work_dir': 'build'}
03:40:31 INFO - [mozharness: 2017-10-20 10:40:31.450119Z] Running clobber step.
03:40:31 INFO - Running pre-action listener: _resource_record_pre_action
03:40:31 INFO - Running main action method: clobber
03:40:31 INFO - rmtree: /builds/slave/test/build
03:40:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:40:33 INFO - Running post-action listener: _resource_record_post_action
03:40:33 INFO - [mozharness: 2017-10-20 10:40:33.034165Z] Finished clobber step (success)
03:40:33 INFO - [mozharness: 2017-10-20 10:40:33.034261Z] Running read-buildbot-config step.
03:40:33 INFO - Running pre-action listener: _resource_record_pre_action
03:40:33 INFO - Running main action method: read_buildbot_config
03:40:33 INFO - Using buildbot properties:
03:40:33 INFO - {
03:40:33 INFO - "project": "",
03:40:33 INFO - "product": "firefox",
03:40:33 INFO - "who": "archaeopteryx@coole-files.de",
03:40:33 INFO - "installer_path": "public/build/target.tar.bz2",
03:40:33 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
03:40:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:40:33 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-stylo-disabled-e10s",
03:40:33 INFO - "stage_platform": "linux64",
03:40:33 INFO - "basedir": "/builds/slave/test",
03:40:33 INFO - "buildnumber": 91,
03:40:33 INFO - "platform": "ubuntu64_hw",
03:40:33 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
03:40:33 INFO - "slavebuilddir": "test",
03:40:33 INFO - "taskId": "GOaQKnYSSyS3SnRvxJJvXQ",
03:40:33 INFO - "branch": "mozilla-central",
03:40:33 INFO - "script_repo_revision": "production",
03:40:33 INFO - "revision": "d1e995c8640a191cd127e87273ec96cb2fabffa9",
03:40:33 INFO - "slavename": "talos-linux64-ix-026",
03:40:33 INFO - "repo_path": "mozilla-central"
03:40:33 INFO - }
03:40:33 INFO - Finding installer, test and symbols from parent task.
03:40:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOaQKnYSSyS3SnRvxJJvXQ'}, attempt #1
03:40:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GOaQKnYSSyS3SnRvxJJvXQ'}, attempt #1
03:40:33 INFO - Task dependencies: ebeSnEKyS2CT1S2m5atBaA
03:40:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA'}, attempt #1
03:40:34 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2
03:40:34 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.test_packages.json
03:40:34 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.crashreporter-symbols.zip
03:40:34 INFO - Running post-action listener: _resource_record_post_action
03:40:34 INFO - [mozharness: 2017-10-20 10:40:34.361270Z] Finished read-buildbot-config step (success)
03:40:34 INFO - [mozharness: 2017-10-20 10:40:34.361489Z] Running download-and-extract step.
03:40:34 INFO - Running pre-action listener: _resource_record_pre_action
03:40:34 INFO - Running main action method: download_and_extract
03:40:34 INFO - mkdir: /builds/slave/test/build/tests
03:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:34 INFO - trying https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.test_packages.json
03:40:34 INFO - Downloading https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:40:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:40:34 INFO - Downloaded 989 bytes.
03:40:34 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:40:34 INFO - Using the following test package requirements:
03:40:34 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:40:34 INFO - u'common': [u'target.common.tests.zip'],
03:40:34 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:40:34 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:40:34 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:40:34 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:40:34 INFO - u'mozbase': [u'target.common.tests.zip'],
03:40:34 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:40:34 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:40:34 INFO - u'web-platform': [u'target.common.tests.zip',
03:40:34 INFO - u'target.web-platform.tests.tar.gz'],
03:40:34 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:40:34 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:40:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.common.tests.zip
03:40:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:40:34 INFO - Fetch https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.common.tests.zip into memory
03:40:37 INFO - Content-Length response header: 55949794
03:40:37 INFO - Bytes received: 55949794
03:40:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.talos.tests.zip
03:40:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:40:48 INFO - Fetch https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.talos.tests.zip into memory
03:40:49 INFO - Content-Length response header: 18737502
03:40:49 INFO - Bytes received: 18737502
03:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:49 INFO - trying https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2
03:40:49 INFO - Downloading https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:40:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:40:53 INFO - Downloaded 62550729 bytes.
03:40:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2
03:40:53 INFO - mkdir: /builds/slave/test/properties
03:40:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:40:53 INFO - Writing to file /builds/slave/test/properties/build_url
03:40:53 INFO - Contents:
03:40:53 INFO - build_url:https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2
03:40:53 INFO - Running post-action listener: _resource_record_post_action
03:40:53 INFO - Running post-action listener: find_tests_for_verification
03:40:53 INFO - Running post-action listener: set_extra_try_arguments
03:40:53 INFO - [mozharness: 2017-10-20 10:40:53.024209Z] Finished download-and-extract step (success)
03:40:53 INFO - [mozharness: 2017-10-20 10:40:53.024303Z] Running populate-webroot step.
03:40:53 INFO - Running pre-action listener: _resource_record_pre_action
03:40:53 INFO - Running main action method: populate_webroot
03:40:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:40:53 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['tresize',
03:40:53 INFO - 'tcanvasmark']},
03:40:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:40:53 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['dromaeo_css',
03:40:53 INFO - 'kraken']},
03:40:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:40:53 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:40:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'tests': ['damp', 'tps']},
03:40:53 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['damp', 'tps']},
03:40:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:40:53 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['dromaeo_dom']},
03:40:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
03:40:53 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['basic_compositor_video',
03:40:53 INFO - 'glvideo']},
03:40:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:40:53 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['ts_paint_webext',
03:40:53 INFO - 'tp5o_webext']},
03:40:53 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:40:53 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:40:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:40:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:40:53 INFO - 'talos_options': ['--mitmproxy',
03:40:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:40:53 INFO - '--firstNonBlankPaint'],
03:40:53 INFO - 'tests': ['tp6_google_heavy',
03:40:53 INFO - 'tp6_youtube_heavy',
03:40:53 INFO - 'tp6_amazon_heavy',
03:40:53 INFO - 'tp6_facebook_heavy']},
03:40:53 INFO - 'other-e10s': {'tests': ['a11yr',
03:40:53 INFO - 'ts_paint',
03:40:53 INFO - 'tpaint',
03:40:53 INFO - 'sessionrestore',
03:40:53 INFO - 'sessionrestore_many_windows',
03:40:53 INFO - 'sessionrestore_no_auto_restore',
03:40:53 INFO - 'tabpaint',
03:40:53 INFO - 'cpstartup']},
03:40:53 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['a11yr',
03:40:53 INFO - 'ts_paint',
03:40:53 INFO - 'tpaint',
03:40:53 INFO - 'sessionrestore',
03:40:53 INFO - 'sessionrestore_many_windows',
03:40:53 INFO - 'sessionrestore_no_auto_restore',
03:40:53 INFO - 'tabpaint',
03:40:53 INFO - 'cpstartup']},
03:40:53 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:40:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:40:53 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['perf_reftest_singletons']},
03:40:53 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['perf_reftest']},
03:40:53 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:40:53 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['speedometer']},
03:40:53 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:40:53 INFO - 'tsvgr_opacity',
03:40:53 INFO - 'tart',
03:40:53 INFO - 'tscrollx',
03:40:53 INFO - 'tsvg_static']},
03:40:53 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['tsvgx',
03:40:53 INFO - 'tsvgr_opacity',
03:40:53 INFO - 'tart',
03:40:53 INFO - 'tscrollx',
03:40:53 INFO - 'tsvg_static']},
03:40:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:40:53 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'talos_options': ['--disable-stylo'],
03:40:53 INFO - 'tests': ['tp5o']},
03:40:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:40:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:40:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:40:53 INFO - 'talos_options': ['--mitmproxy',
03:40:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:40:53 INFO - '--firstNonBlankPaint'],
03:40:53 INFO - 'tests': ['tp6_google',
03:40:53 INFO - 'tp6_youtube',
03:40:53 INFO - 'tp6_amazon',
03:40:53 INFO - 'tp6_facebook']},
03:40:53 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:40:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:40:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:40:53 INFO - 'talos_options': ['--disable-stylo',
03:40:53 INFO - '--mitmproxy',
03:40:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:40:53 INFO - '--firstNonBlankPaint'],
03:40:53 INFO - 'tests': ['tp6_google',
03:40:53 INFO - 'tp6_youtube',
03:40:53 INFO - 'tp6_amazon',
03:40:53 INFO - 'tp6_facebook']},
03:40:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:40:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:40:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:40:53 INFO - 'talos_options': ['--stylo-threads=1',
03:40:53 INFO - '--mitmproxy',
03:40:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:40:53 INFO - '--firstNonBlankPaint'],
03:40:53 INFO - 'tests': ['tp6_google',
03:40:53 INFO - 'tp6_youtube',
03:40:53 INFO - 'tp6_amazon',
03:40:53 INFO - 'tp6_facebook']},
03:40:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'talos_options': ['--xperf_path',
03:40:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:40:53 INFO - 'tests': ['tp5n']},
03:40:53 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:40:53 INFO - 'talos_options': ['--disable-stylo',
03:40:53 INFO - '--xperf_path',
03:40:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:40:53 INFO - 'tests': ['tp5n']}}}
03:40:53 INFO - Running post-action listener: _resource_record_post_action
03:40:53 INFO - [mozharness: 2017-10-20 10:40:53.042436Z] Finished populate-webroot step (success)
03:40:53 INFO - [mozharness: 2017-10-20 10:40:53.042524Z] Running create-virtualenv step.
03:40:53 INFO - Running pre-action listener: _resource_record_pre_action
03:40:53 INFO - Running main action method: create_virtualenv
03:40:53 INFO - Creating virtualenv /builds/slave/test/build/venv
03:40:53 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
03:40:53 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:40:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:40:53 INFO - Using real prefix '/usr'
03:40:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:40:53 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:40:55 INFO - Installing setuptools, pip, wheel...done.
03:40:55 INFO - Return code: 0
03:40:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:40:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
03:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
03:40:55 INFO - Using env: {'DISPLAY': ':0',
03:40:55 INFO - 'HOME': '/home/cltbld',
03:40:55 INFO - 'LANG': 'en_US.UTF-8',
03:40:55 INFO - 'LANGUAGE': 'en_US:en',
03:40:55 INFO - 'LOGNAME': 'cltbld',
03:40:55 INFO - 'MAIL': '/var/mail/cltbld',
03:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:40:55 INFO - 'MOZ_NO_REMOTE': '1',
03:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:40:55 INFO - 'NO_EM_RESTART': '1',
03:40:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:40:55 INFO - 'PWD': '/builds/slave/test',
03:40:55 INFO - 'SHELL': '/bin/bash',
03:40:55 INFO - 'SHLVL': '1',
03:40:55 INFO - 'TERM': 'linux',
03:40:55 INFO - 'TMOUT': '86400',
03:40:55 INFO - 'USER': 'cltbld',
03:40:55 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:40:55 INFO - '_': '/tools/buildbot/bin/python'}
03:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple
03:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:40:55 INFO - Return code: 0
03:40:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:40:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
03:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
03:40:55 INFO - Using env: {'DISPLAY': ':0',
03:40:55 INFO - 'HOME': '/home/cltbld',
03:40:55 INFO - 'LANG': 'en_US.UTF-8',
03:40:55 INFO - 'LANGUAGE': 'en_US:en',
03:40:55 INFO - 'LOGNAME': 'cltbld',
03:40:55 INFO - 'MAIL': '/var/mail/cltbld',
03:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:40:55 INFO - 'MOZ_NO_REMOTE': '1',
03:40:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:40:55 INFO - 'NO_EM_RESTART': '1',
03:40:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:40:55 INFO - 'PWD': '/builds/slave/test',
03:40:55 INFO - 'SHELL': '/bin/bash',
03:40:55 INFO - 'SHLVL': '1',
03:40:55 INFO - 'TERM': 'linux',
03:40:55 INFO - 'TMOUT': '86400',
03:40:55 INFO - 'USER': 'cltbld',
03:40:55 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:40:55 INFO - '_': '/tools/buildbot/bin/python'}
03:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple
03:40:55 INFO - Collecting psutil>=3.1.1
03:40:56 INFO - Installing collected packages: psutil
03:40:57 INFO - Successfully installed psutil-3.1.1
03:40:57 INFO - Return code: 0
03:40:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:40:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:40:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
03:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
03:40:57 INFO - Using env: {'DISPLAY': ':0',
03:40:57 INFO - 'HOME': '/home/cltbld',
03:40:57 INFO - 'LANG': 'en_US.UTF-8',
03:40:57 INFO - 'LANGUAGE': 'en_US:en',
03:40:57 INFO - 'LOGNAME': 'cltbld',
03:40:57 INFO - 'MAIL': '/var/mail/cltbld',
03:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:40:57 INFO - 'MOZ_NO_REMOTE': '1',
03:40:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:40:57 INFO - 'NO_EM_RESTART': '1',
03:40:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:40:57 INFO - 'PWD': '/builds/slave/test',
03:40:57 INFO - 'SHELL': '/bin/bash',
03:40:57 INFO - 'SHLVL': '1',
03:40:57 INFO - 'TERM': 'linux',
03:40:57 INFO - 'TMOUT': '86400',
03:40:57 INFO - 'USER': 'cltbld',
03:40:57 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:40:57 INFO - '_': '/tools/buildbot/bin/python'}
03:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple
03:40:57 INFO - Collecting mozsystemmonitor==0.3
03:40:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:40:58 INFO - Installing collected packages: mozsystemmonitor
03:40:58 INFO - Successfully installed mozsystemmonitor-0.3
03:40:58 INFO - Return code: 0
03:40:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:40:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:40:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:40:58 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 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:40:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
03:40:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
03:40:58 INFO - Using env: {'DISPLAY': ':0',
03:40:58 INFO - 'HOME': '/home/cltbld',
03:40:58 INFO - 'LANG': 'en_US.UTF-8',
03:40:58 INFO - 'LANGUAGE': 'en_US:en',
03:40:58 INFO - 'LOGNAME': 'cltbld',
03:40:58 INFO - 'MAIL': '/var/mail/cltbld',
03:40:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:40:58 INFO - 'MOZ_NO_REMOTE': '1',
03:40:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:40:58 INFO - 'NO_EM_RESTART': '1',
03:40:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:40:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:40:58 INFO - 'PWD': '/builds/slave/test',
03:40:58 INFO - 'SHELL': '/bin/bash',
03:40:58 INFO - 'SHLVL': '1',
03:40:58 INFO - 'TERM': 'linux',
03:40:58 INFO - 'TMOUT': '86400',
03:40:58 INFO - 'USER': 'cltbld',
03:40:58 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:40:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:40:58 INFO - '_': '/tools/buildbot/bin/python'}
03:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple
03:40:59 INFO - Collecting jsonschema==2.5.1
03:41:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:41:00 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:41:01 INFO - Installing collected packages: functools32, jsonschema
03:41:01 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:41:01 INFO - Return code: 0
03:41:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:41:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:41:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
03:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
03:41:01 INFO - Using env: {'DISPLAY': ':0',
03:41:01 INFO - 'HOME': '/home/cltbld',
03:41:01 INFO - 'LANG': 'en_US.UTF-8',
03:41:01 INFO - 'LANGUAGE': 'en_US:en',
03:41:01 INFO - 'LOGNAME': 'cltbld',
03:41:01 INFO - 'MAIL': '/var/mail/cltbld',
03:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:01 INFO - 'MOZ_NO_REMOTE': '1',
03:41:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:01 INFO - 'NO_EM_RESTART': '1',
03:41:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:01 INFO - 'PWD': '/builds/slave/test',
03:41:01 INFO - 'SHELL': '/bin/bash',
03:41:01 INFO - 'SHLVL': '1',
03:41:01 INFO - 'TERM': 'linux',
03:41:01 INFO - 'TMOUT': '86400',
03:41:01 INFO - 'USER': 'cltbld',
03:41:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:01 INFO - '_': '/tools/buildbot/bin/python'}
03:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple
03:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:41:01 INFO - Return code: 0
03:41:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:41:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:41:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
03:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
03:41:01 INFO - Using env: {'DISPLAY': ':0',
03:41:01 INFO - 'HOME': '/home/cltbld',
03:41:01 INFO - 'LANG': 'en_US.UTF-8',
03:41:01 INFO - 'LANGUAGE': 'en_US:en',
03:41:01 INFO - 'LOGNAME': 'cltbld',
03:41:01 INFO - 'MAIL': '/var/mail/cltbld',
03:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:01 INFO - 'MOZ_NO_REMOTE': '1',
03:41:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:01 INFO - 'NO_EM_RESTART': '1',
03:41:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:01 INFO - 'PWD': '/builds/slave/test',
03:41:01 INFO - 'SHELL': '/bin/bash',
03:41:01 INFO - 'SHLVL': '1',
03:41:01 INFO - 'TERM': 'linux',
03:41:01 INFO - 'TMOUT': '86400',
03:41:01 INFO - 'USER': 'cltbld',
03:41:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:01 INFO - '_': '/tools/buildbot/bin/python'}
03:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple
03:41:02 INFO - Collecting blobuploader==1.2.4
03:41:03 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:41:04 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:41:05 INFO - Installing collected packages: requests, docopt, blobuploader
03:41:05 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:41:05 INFO - Return code: 0
03:41:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:41:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:41:05 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 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:41:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
03:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:41:05 INFO - Using env: {'DISPLAY': ':0',
03:41:05 INFO - 'HOME': '/home/cltbld',
03:41:05 INFO - 'LANG': 'en_US.UTF-8',
03:41:05 INFO - 'LANGUAGE': 'en_US:en',
03:41:05 INFO - 'LOGNAME': 'cltbld',
03:41:05 INFO - 'MAIL': '/var/mail/cltbld',
03:41:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:05 INFO - 'MOZ_NO_REMOTE': '1',
03:41:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:05 INFO - 'NO_EM_RESTART': '1',
03:41:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:05 INFO - 'PWD': '/builds/slave/test',
03:41:05 INFO - 'SHELL': '/bin/bash',
03:41:05 INFO - 'SHLVL': '1',
03:41:05 INFO - 'TERM': 'linux',
03:41:05 INFO - 'TMOUT': '86400',
03:41:05 INFO - 'USER': 'cltbld',
03:41:05 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:05 INFO - '_': '/tools/buildbot/bin/python'}
03:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple
03:41:06 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:41:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:41:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:41:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:41:06 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:41:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:41:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:41:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:41:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:41:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:41:08 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:41:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:41:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:41:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:41:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:41:08 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:41:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:41:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:41:09 INFO - Running setup.py install for manifestparser: started
03:41:09 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:41:09 INFO - Running setup.py install for mozcrash: started
03:41:09 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:41:09 INFO - Running setup.py install for mozdebug: started
03:41:09 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:41:10 INFO - Running setup.py install for mozdevice: started
03:41:10 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:41:10 INFO - Running setup.py install for mozfile: started
03:41:10 INFO - Running setup.py install for mozfile: finished with status 'done'
03:41:10 INFO - Running setup.py install for mozhttpd: started
03:41:10 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:41:10 INFO - Running setup.py install for mozinfo: started
03:41:10 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:41:10 INFO - Running setup.py install for mozInstall: started
03:41:11 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:41:11 INFO - Running setup.py install for mozleak: started
03:41:11 INFO - Running setup.py install for mozleak: finished with status 'done'
03:41:11 INFO - Running setup.py install for mozlog: started
03:41:11 INFO - Running setup.py install for mozlog: finished with status 'done'
03:41:11 INFO - Running setup.py install for moznetwork: started
03:41:11 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:41:11 INFO - Running setup.py install for mozprocess: started
03:41:11 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:41:11 INFO - Running setup.py install for mozprofile: started
03:41:12 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:41:12 INFO - Running setup.py install for mozrunner: started
03:41:12 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:41:12 INFO - Running setup.py install for mozscreenshot: started
03:41:12 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:41:12 INFO - Running setup.py install for moztest: started
03:41:12 INFO - Running setup.py install for moztest: finished with status 'done'
03:41:12 INFO - Running setup.py install for mozversion: started
03:41:13 INFO - Running setup.py install for mozversion: finished with status 'done'
03:41:13 INFO - Successfully installed manifestparser-1.1 mozInstall-1.13 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.28 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
03:41:13 INFO - Return code: 0
03:41:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:41:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:41:13 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 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:41:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
03:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:41:13 INFO - Using env: {'DISPLAY': ':0',
03:41:13 INFO - 'HOME': '/home/cltbld',
03:41:13 INFO - 'LANG': 'en_US.UTF-8',
03:41:13 INFO - 'LANGUAGE': 'en_US:en',
03:41:13 INFO - 'LOGNAME': 'cltbld',
03:41:13 INFO - 'MAIL': '/var/mail/cltbld',
03:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:13 INFO - 'MOZ_NO_REMOTE': '1',
03:41:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:13 INFO - 'NO_EM_RESTART': '1',
03:41:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:13 INFO - 'PWD': '/builds/slave/test',
03:41:13 INFO - 'SHELL': '/bin/bash',
03:41:13 INFO - 'SHLVL': '1',
03:41:13 INFO - 'TERM': 'linux',
03:41:13 INFO - 'TMOUT': '86400',
03:41:13 INFO - 'USER': 'cltbld',
03:41:13 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:13 INFO - '_': '/tools/buildbot/bin/python'}
03:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple
03:41:13 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:41:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:41:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:41:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:41:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:41:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
03:41:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:41:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:41:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:41:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
03:41:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
03:41:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
03:41:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
03:41:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
03:41:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:41:15 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
03:41:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
03:41:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
03:41:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:41:16 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))
03:41:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:41:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
03:41:16 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
03:41:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
03:41:17 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:41:18 INFO - Installing collected packages: blessings
03:41:18 INFO - Successfully installed blessings-1.6
03:41:18 INFO - Return code: 0
03:41:18 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:41:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:41:19 INFO - Reading from file tmpfile_stdout
03:41:19 INFO - Reading from file tmpfile_stderr
03:41:19 INFO - Current package versions:
03:41:19 INFO - blessings == 1.6
03:41:19 INFO - blobuploader == 1.2.4
03:41:19 INFO - docopt == 0.6.1
03:41:19 INFO - functools32 == 3.2.3.post2
03:41:19 INFO - jsonschema == 2.5.1
03:41:19 INFO - manifestparser == 1.1
03:41:19 INFO - mozInstall == 1.13
03:41:19 INFO - mozcrash == 1.0
03:41:19 INFO - mozdebug == 0.1
03:41:19 INFO - mozdevice == 0.51
03:41:19 INFO - mozfile == 1.2
03:41:19 INFO - mozhttpd == 0.7
03:41:19 INFO - mozinfo == 0.10
03:41:19 INFO - mozleak == 0.1
03:41:19 INFO - mozlog == 3.5
03:41:19 INFO - moznetwork == 0.27
03:41:19 INFO - mozprocess == 0.25
03:41:19 INFO - mozprofile == 0.28
03:41:19 INFO - mozrunner == 6.13
03:41:19 INFO - mozscreenshot == 0.1
03:41:19 INFO - mozsystemmonitor == 0.3
03:41:19 INFO - moztest == 0.8
03:41:19 INFO - mozversion == 1.4
03:41:19 INFO - psutil == 3.1.1
03:41:19 INFO - requests == 1.2.3
03:41:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:41:19 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 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:41:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
03:41:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:41:19 INFO - Using env: {'DISPLAY': ':0',
03:41:19 INFO - 'HOME': '/home/cltbld',
03:41:19 INFO - 'LANG': 'en_US.UTF-8',
03:41:19 INFO - 'LANGUAGE': 'en_US:en',
03:41:19 INFO - 'LOGNAME': 'cltbld',
03:41:19 INFO - 'MAIL': '/var/mail/cltbld',
03:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:19 INFO - 'MOZ_NO_REMOTE': '1',
03:41:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:19 INFO - 'NO_EM_RESTART': '1',
03:41:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:19 INFO - 'PWD': '/builds/slave/test',
03:41:19 INFO - 'SHELL': '/bin/bash',
03:41:19 INFO - 'SHLVL': '1',
03:41:19 INFO - 'TERM': 'linux',
03:41:19 INFO - 'TMOUT': '86400',
03:41:19 INFO - 'USER': 'cltbld',
03:41:19 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:19 INFO - '_': '/tools/buildbot/bin/python'}
03:41:19 INFO - Ignoring indexes: https://pypi.python.org/simple
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
03:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
03:41:19 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:41:20 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:41:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:41:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:41:21 INFO - Installing collected packages: simplejson, requests
03:41:21 INFO - Found existing installation: requests 1.2.3
03:41:21 INFO - Uninstalling requests-1.2.3:
03:41:21 INFO - Successfully uninstalled requests-1.2.3
03:41:22 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
03:41:22 INFO - Return code: 0
03:41:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:41:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12361f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13ffa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a3cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, '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': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
03:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
03:41:22 INFO - Using env: {'DISPLAY': ':0',
03:41:22 INFO - 'HOME': '/home/cltbld',
03:41:22 INFO - 'LANG': 'en_US.UTF-8',
03:41:22 INFO - 'LANGUAGE': 'en_US:en',
03:41:22 INFO - 'LOGNAME': 'cltbld',
03:41:22 INFO - 'MAIL': '/var/mail/cltbld',
03:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:22 INFO - 'MOZ_NO_REMOTE': '1',
03:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:22 INFO - 'NO_EM_RESTART': '1',
03:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:22 INFO - 'PWD': '/builds/slave/test',
03:41:22 INFO - 'SHELL': '/bin/bash',
03:41:22 INFO - 'SHLVL': '1',
03:41:22 INFO - 'TERM': 'linux',
03:41:22 INFO - 'TMOUT': '86400',
03:41:22 INFO - 'USER': 'cltbld',
03:41:22 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:22 INFO - '_': '/tools/buildbot/bin/python'}
03:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple
03:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:41:22 INFO - Return code: 0
03:41:22 INFO - Running post-action listener: _resource_record_post_action
03:41:22 INFO - Running post-action listener: _start_resource_monitoring
03:41:22 INFO - Starting resource monitoring.
03:41:22 INFO - [mozharness: 2017-10-20 10:41:22.682832Z] Finished create-virtualenv step (success)
03:41:22 INFO - [mozharness: 2017-10-20 10:41:22.683343Z] Running install step.
03:41:22 INFO - Running pre-action listener: _resource_record_pre_action
03:41:22 INFO - Running main action method: install
03:41:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:41:23 INFO - Reading from file tmpfile_stdout
03:41:23 INFO - Reading from file tmpfile_stderr
03:41:23 INFO - Detecting whether we're running mozinstall >=1.0...
03:41:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:41:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:41:23 INFO - Reading from file tmpfile_stdout
03:41:23 INFO - Output received:
03:41:23 INFO - Usage: mozinstall [options] installer
03:41:23 INFO - Options:
03:41:23 INFO - -h, --help show this help message and exit
03:41:23 INFO - -d DEST, --destination=DEST
03:41:23 INFO - Directory to install application into. [default:
03:41:23 INFO - "/builds/slave/test"]
03:41:23 INFO - --app=APP Application being installed. [default: firefox]
03:41:23 INFO - mkdir: /builds/slave/test/build/application
03:41:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
03:41:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:41:41 INFO - Reading from file tmpfile_stdout
03:41:41 INFO - Output received:
03:41:41 INFO - /builds/slave/test/build/application/firefox/firefox
03:41:41 INFO - Running post-action listener: _resource_record_post_action
03:41:41 INFO - [mozharness: 2017-10-20 10:41:41.506466Z] Finished install step (success)
03:41:41 INFO - [mozharness: 2017-10-20 10:41:41.506713Z] Running setup-mitmproxy step.
03:41:41 INFO - Running pre-action listener: _resource_record_pre_action
03:41:41 INFO - Running main action method: setup_mitmproxy
03:41:41 INFO - Skipping: mitmproxy is not required
03:41:41 INFO - Running post-action listener: _resource_record_post_action
03:41:41 INFO - [mozharness: 2017-10-20 10:41:41.507835Z] Finished setup-mitmproxy step (success)
03:41:41 INFO - [mozharness: 2017-10-20 10:41:41.508051Z] Running run-tests step.
03:41:41 INFO - Running pre-action listener: _resource_record_pre_action
03:41:41 INFO - Running pre-action listener: _set_gcov_prefix
03:41:41 INFO - Running main action method: run_tests
03:41:41 WARNING - Try message not found.
03:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:41:41 INFO - Python 2.7.3
03:41:41 INFO - Return code: 0
03:41:41 INFO - grabbing minidump binary from tooltool
03:41: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')]}
03:41:41 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 0x15a0e60>, '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 0x15a1d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15a21f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:41:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
03:41:41 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
03:41:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
03:41:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:41:41 INFO - Return code: 0
03:41:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:41:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:41:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:41:41 INFO - ENV: RUST_BACKTRACE is now full
03:41:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:41:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:41:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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
03:41:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --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
03:41:41 INFO - Using env: {'DISPLAY': ':0',
03:41:41 INFO - 'HOME': '/home/cltbld',
03:41:41 INFO - 'LANG': 'en_US.UTF-8',
03:41:41 INFO - 'LANGUAGE': 'en_US:en',
03:41:41 INFO - 'LOGNAME': 'cltbld',
03:41:41 INFO - 'MAIL': '/var/mail/cltbld',
03:41:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:41:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:41:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:41 INFO - 'MOZ_NO_REMOTE': '1',
03:41:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:41:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:41 INFO - 'NO_EM_RESTART': '1',
03:41:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:41 INFO - 'PWD': '/builds/slave/test',
03:41:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:41:41 INFO - 'RUST_BACKTRACE': 'full',
03:41:41 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:41:41 INFO - 'SHELL': '/bin/bash',
03:41:41 INFO - 'SHLVL': '1',
03:41:41 INFO - 'TERM': 'linux',
03:41:41 INFO - 'TMOUT': '86400',
03:41:41 INFO - 'USER': 'cltbld',
03:41:41 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:41 INFO - '_': '/tools/buildbot/bin/python'}
03:41:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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
03:41:41 INFO - mozversion application_buildid: 20171020093832
03:41:41 INFO - mozversion application_changeset: d1e995c8640a191cd127e87273ec96cb2fabffa9
03:41:41 INFO - mozversion application_display_name: Nightly
03:41:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:41:41 INFO - mozversion application_name: Firefox
03:41:41 INFO - mozversion application_remotingname: firefox
03:41:41 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:41:41 INFO - mozversion application_vendor: Mozilla
03:41:41 INFO - mozversion application_version: 58.0a1
03:41:41 INFO - mozversion platform_buildid: 20171020093832
03:41:41 INFO - mozversion platform_changeset: d1e995c8640a191cd127e87273ec96cb2fabffa9
03:41:41 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:41:41 INFO - mozversion platform_version: 58.0a1
03:41:41 INFO - using testdate: 1508496101
03:41:41 INFO - actual date: 1508496101
03:41:41 INFO - starting webserver on 'localhost:59636'
03:41:41 INFO - SUITE-START | Running 2 tests
03:41:41 INFO - TEST-START | basic_compositor_video
03:41:41 INFO - Initialising browser for basic_compositor_video test...
03:41:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59636/getInfo.html -profile /tmp/tmpqpWLMJ/profile
03:41:41 INFO - TEST-INFO | started process 10491 (/builds/slave/test/build/application/firefox/firefox http://localhost:59636/getInfo.html)
03:41:47 INFO - TEST-INFO | 10491: exit 0
03:41:47 INFO - Browser initialized.
03:41:47 INFO - Running cycle 1/1 for basic_compositor_video test...
03:41:47 INFO - Using env: {'DISPLAY': ':0',
03:41:47 INFO - 'HOME': '/home/cltbld',
03:41:47 INFO - 'JSGC_DISABLE_POISONING': '1',
03:41:47 INFO - 'LANG': 'en_US.UTF-8',
03:41:47 INFO - 'LANGUAGE': 'en_US:en',
03:41:47 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:41:47 INFO - 'LOGNAME': 'cltbld',
03:41:47 INFO - 'MAIL': '/var/mail/cltbld',
03:41:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:41:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:41:47 INFO - 'MOZ_CRASHREPORTER': '1',
03:41:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:41:47 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:41:47 INFO - 'MOZ_NO_REMOTE': '1',
03:41:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:41:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:41:47 INFO - 'NO_EM_RESTART': '1',
03:41:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:41:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:41:47 INFO - 'PWD': '/builds/slave/test',
03:41:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:41:47 INFO - 'RUST_BACKTRACE': 'full',
03:41:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:41:47 INFO - 'SHELL': '/bin/bash',
03:41:47 INFO - 'SHLVL': '1',
03:41:47 INFO - 'STYLO_FORCE_DISABLED': '1',
03:41:47 INFO - 'TERM': 'linux',
03:41:47 INFO - 'TMOUT': '86400',
03:41:47 INFO - 'USER': 'cltbld',
03:41:47 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:41:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:41:47 INFO - '_': '/tools/buildbot/bin/python'}
03:41:47 INFO - TEST-INFO | started process 10757 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqpWLMJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
03:41:48 INFO - PID 10757 |
03:41:48 INFO - PID 10757 | (/builds/slave/test/build/application/firefox/firefox:10807): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:41:48 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6876490438695164 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6700292153589313 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.744776162790698 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7653751103265667 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8921011673151744 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.016024096385541 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.6816503067484687 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4258818493150627 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.667161741835149 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635704225352111 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.585591054313102 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.349906542056079 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.409961977186311 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.989917582417588 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347304526748989 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.698771929824549 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.454236641221382 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932759562841529 ms/frame
03:42:34 INFO - PID 10757 |
03:42:34 INFO - PID 10757 | Cycle 1(1): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:43:20 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6801595744680853 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6752847571189275 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7560415447630198 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.753400525854513 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0442991913746615 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.099795081967215 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.517831184056273 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3965534804753816 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.651666666666666 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.391954177897577 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 10.036638795986615 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.350607476635526 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.1957462686567 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.753413978494635 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348683127572029 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.769205882352939 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.72121093749999 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111583333333328 ms/frame
03:43:20 INFO - PID 10757 |
03:43:20 INFO - PID 10757 | Cycle 1(2): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:44:06 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6866160764474425 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6731312709030097 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7597653958944284 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7114841745081268 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.09974043715847 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.149885892116184 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.5633135391924005 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.408943781942077 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.63062851782364 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.652344632768362 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.618285256410253 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.094204545454556 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.363655303030306 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.699331550802146 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500833333333352 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.908333333333324 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.722480468749978 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11669444444447 ms/frame
03:44:06 INFO - PID 10757 |
03:44:06 INFO - PID 10757 | Cycle 1(3): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:44:52 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819226457399103 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670208333333334 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7914059701492535 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7767406749555956 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.138462068965517 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.091196319018404 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.559875444839858 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.468093587521664 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683844696969697 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.512272727272732 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.742272727272724 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.524952380952389 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.408840304182515 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.177150837988822 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.716186440677953 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.975778443113782 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.454217557251926 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87119565217389 ms/frame
03:44:52 INFO - PID 10757 |
03:44:52 INFO - PID 10757 | Cycle 1(4): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:45:39 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.696455624646693 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682944120100085 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7497055393586005 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.73627170138889 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.220042194092826 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.090817995910024 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.5812171837708826 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5913913824057495 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.628827392120077 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.63684507042253 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 10.456341463414638 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.4341037735849 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.628527131782946 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.050027624309394 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39818181818182 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.837011834319537 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630988372093016 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.238398876404482 ms/frame
03:45:39 INFO - PID 10757 |
03:45:39 INFO - PID 10757 | Cycle 1(5): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:46:25 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.684102132435466 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710568086883877 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7681820860341777 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7324632034632033 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0763722826086966 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.2200316455696205 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.672399020807832 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6245380434782657 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683115530303026 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.465710382513663 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 10.06802013422819 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.437429245283022 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.322962264150954 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81470270270267 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.195691056910567 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.837218934911274 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.497164750957863 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.873668478260855 ms/frame
03:46:25 INFO - PID 10757 |
03:46:25 INFO - PID 10757 | Cycle 1(6): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:47:11 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6822029147982063 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6743640167364018 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.792574671445639 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7595954265611256 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.121407967032967 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8468461538461516 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.628065296251509 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.630580762250455 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.814467054263572 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572325905292479 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.967906976744187 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.666376811594214 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.32418867924527 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.757043010752716 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.452095435684654 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.911547619047615 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.62986434108529 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.051823204419875 ms/frame
03:47:11 INFO - PID 10757 |
03:47:11 INFO - PID 10757 | Cycle 1(7): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:47:57 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838215488215489 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6702253756260432 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.809650180940893 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7723560673162095 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.049730094466937 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.211136842105261 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.6369090909090898 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.566149732620322 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.792789575289577 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572743732590534 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.435408805031443 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.221774193548388 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.322905660377357 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.755322580645151 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.50147916666665 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.840266272189377 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.409144486692027 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.584338624338637 ms/frame
03:47:57 INFO - PID 10757 |
03:47:57 INFO - PID 10757 | Cycle 1(8): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:48:43 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6866273187183813 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669874791318865 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7806290801186948 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7876764968722072 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.202822128851541 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.150217842323649 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.6865909090909117 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6051531531531475 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.792258687258693 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.68311079545454 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 10.242030716723553 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.393403755868553 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.499118773946362 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.695401069518722 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348004115226347 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.198231707317042 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.40880228136882 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991263736263756 ms/frame
03:48:43 INFO - PID 10757 |
03:48:43 INFO - PID 10757 | Cycle 1(9): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:49:30 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6875337457817772 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6756448911222783 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8099517490952952 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7794617437722418 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.110931506849314 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.090265848670759 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.5302058823529405 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4251541095890428 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650047080979283 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.749051724137937 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.901056105610563 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.136050228310511 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.323113207547156 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.992637362637382 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.553263598326373 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.978143712574841 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540576923076934 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.989972527472503 ms/frame
03:49:30 INFO - PID 10757 |
03:49:30 INFO - PID 10757 | Cycle 1(10): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:50:16 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789983212087296 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6691659716430363 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7838079667063025 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.792809139784946 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.262109375 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.115925925925926 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.521813380281689 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4130972696245747 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.759309021113248 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.667549575070823 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.870592105263157 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.434245283018857 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.451106870229003 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.050165745856363 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.555857740585768 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83964497041421 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.541288461538464 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17670391061455 ms/frame
03:50:16 INFO - PID 10757 |
03:50:16 INFO - PID 10757 | Cycle 1(11): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:51:02 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6906929577464789 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669291666666668 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7816211401425177 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7943452914798204 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_startup = 4.099323770491803 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.2305391120507405 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_startup = 3.5642042755344434 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1_inclip = 3.432092624356775 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.907224409448817 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.48015068493151 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_startup = 9.837819672131147 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.480p.60fps.webm_scale_2_inclip = 9.95519900497512 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.280075187969924 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81243243243241 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.500437500000013 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.051536144578314 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.322490566037732 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.698315508021386 ms/frame
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | Cycle 1(12): loaded http://localhost:59636/tests/video/video_playback.html (next: http://localhost:59636/tests/video/video_playback.html)
03:51:02 INFO - PID 10757 | __start_tp_report
03:51:02 INFO - PID 10757 | _x_x_mozilla_page_load
03:51:02 INFO - PID 10757 | _x_x_mozilla_page_load_details
03:51:02 INFO - PID 10757 | |i|pagename|runs|
03:51:02 INFO - PID 10757 | |0;240p.120fps.mp4_scale_1_startup;1.6876490438695164;1.6801595744680853;1.6866160764474425;1.6819226457399103;1.696455624646693;1.684102132435466;1.6822029147982063;1.6838215488215489;1.6866273187183813;1.6875337457817772;1.6789983212087296;1.6906929577464789
03:51:02 INFO - PID 10757 | |1;240p.120fps.mp4_scale_1_inclip;1.6700292153589313;1.6752847571189275;1.6731312709030097;1.6670208333333334;1.6682944120100085;1.6710568086883877;1.6743640167364018;1.6702253756260432;1.669874791318865;1.6756448911222783;1.6691659716430363;1.6669291666666668
03:51:02 INFO - PID 10757 | |2;240p.120fps.mp4_scale_1.1_startup;1.744776162790698;1.7560415447630198;1.7597653958944284;1.7914059701492535;1.7497055393586005;1.7681820860341777;1.792574671445639;1.809650180940893;1.7806290801186948;1.8099517490952952;1.7838079667063025;1.7816211401425177
03:51:02 INFO - PID 10757 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7653751103265667;1.753400525854513;1.7114841745081268;1.7767406749555956;1.73627170138889;1.7324632034632033;1.7595954265611256;1.7723560673162095;1.7876764968722072;1.7794617437722418;1.792809139784946;1.7943452914798204
03:51:02 INFO - PID 10757 | |4;240p.120fps.mp4_scale_2_startup;3.8921011673151744;4.0442991913746615;4.09974043715847;4.138462068965517;4.220042194092826;4.0763722826086966;4.121407967032967;4.049730094466937;4.202822128851541;4.110931506849314;4.262109375;4.099323770491803
03:51:02 INFO - PID 10757 | |5;240p.120fps.mp4_scale_2_inclip;4.016024096385541;4.099795081967215;4.149885892116184;4.091196319018404;4.090817995910024;4.2200316455696205;3.8468461538461516;4.211136842105261;4.150217842323649;4.090265848670759;4.115925925925926;4.2305391120507405
03:51:02 INFO - PID 10757 | |6;480p.60fps.webm_scale_1_startup;3.6816503067484687;3.517831184056273;3.5633135391924005;3.559875444839858;3.5812171837708826;3.672399020807832;3.628065296251509;3.6369090909090898;3.6865909090909117;3.5302058823529405;3.521813380281689;3.5642042755344434
03:51:02 INFO - PID 10757 | |7;480p.60fps.webm_scale_1_inclip;3.4258818493150627;3.3965534804753816;3.408943781942077;3.468093587521664;3.5913913824057495;3.6245380434782657;3.630580762250455;3.566149732620322;3.6051531531531475;3.4251541095890428;3.4130972696245747;3.432092624356775
03:51:02 INFO - PID 10757 | |8;480p.60fps.webm_scale_1.1_startup;4.667161741835149;5.651666666666666;5.63062851782364;5.683844696969697;5.628827392120077;5.683115530303026;5.814467054263572;5.792789575289577;5.792258687258693;5.650047080979283;5.759309021113248;5.907224409448817
03:51:02 INFO - PID 10757 | |9;480p.60fps.webm_scale_1.1_inclip;5.635704225352111;5.391954177897577;5.652344632768362;5.512272727272732;5.63684507042253;5.465710382513663;5.572325905292479;5.572743732590534;5.68311079545454;5.749051724137937;5.667549575070823;5.48015068493151
03:51:02 INFO - PID 10757 | |10;480p.60fps.webm_scale_2_startup;9.585591054313102;10.036638795986615;9.618285256410253;9.742272727272724;10.456341463414638;10.06802013422819;9.967906976744187;9.435408805031443;10.242030716723553;9.901056105610563;9.870592105263157;9.837819672131147
03:51:02 INFO - PID 10757 | |11;480p.60fps.webm_scale_2_inclip;9.349906542056079;9.350607476635526;9.094204545454556;9.524952380952389;9.4341037735849;9.437429245283022;9.666376811594214;9.221774193548388;9.393403755868553;9.136050228310511;9.434245283018857;9.95519900497512
03:51:02 INFO - PID 10757 | |12;1080p.60fps.mp4_scale_1_startup;11.409961977186311;11.1957462686567;11.363655303030306;11.408840304182515;11.628527131782946;11.322962264150954;11.32418867924527;11.322905660377357;11.499118773946362;11.323113207547156;11.451106870229003;11.280075187969924
03:51:02 INFO - PID 10757 | |13;1080p.60fps.mp4_scale_1_inclip;10.989917582417588;10.753413978494635;10.699331550802146;11.177150837988822;11.050027624309394;10.81470270270267;10.757043010752716;10.755322580645151;10.695401069518722;10.992637362637382;11.050165745856363;10.81243243243241
03:51:02 INFO - PID 10757 | |14;1080p.60fps.mp4_scale_1.1_startup;12.347304526748989;12.348683127572029;12.500833333333352;12.716186440677953;12.39818181818182;12.195691056910567;12.452095435684654;12.50147916666665;12.348004115226347;12.553263598326373;12.555857740585768;12.500437500000013
03:51:02 INFO - PID 10757 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.698771929824549;11.769205882352939;11.908333333333324;11.975778443113782;11.837011834319537;11.837218934911274;11.911547619047615;11.840266272189377;12.198231707317042;11.978143712574841;11.83964497041421;12.051536144578314
03:51:02 INFO - PID 10757 | |16;1080p.60fps.mp4_scale_2_startup;11.454236641221382;11.72121093749999;11.722480468749978;11.454217557251926;11.630988372093016;11.497164750957863;11.62986434108529;11.409144486692027;11.40880228136882;11.540576923076934;11.541288461538464;11.322490566037732
03:51:02 INFO - PID 10757 | |17;1080p.60fps.mp4_scale_2_inclip;10.932759562841529;11.111583333333328;11.11669444444447;10.87119565217389;11.238398876404482;10.873668478260855;11.051823204419875;10.584338624338637;10.991263736263756;10.989972527472503;11.17670391061455;10.698315508021386
03:51:02 INFO - PID 10757 | __end_tp_report
03:51:02 INFO - PID 10757 | __start_cc_report
03:51:02 INFO - PID 10757 | _x_x_mozilla_cycle_collect,599
03:51:02 INFO - PID 10757 | __end_cc_report
03:51:02 INFO - PID 10757 | __startTimestamp1508496662643__endTimestamp
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | ------- Summary: start -------
03:51:02 INFO - PID 10757 | Number of tests: 18
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.69 Median:1.69 stddev:0.00 (0.3%) stddev-sans-first:0.01
03:51:02 INFO - PID 10757 | 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
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#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
03:51:02 INFO - PID 10757 | 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
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.78 Median:1.78 stddev:0.02 (1.2%) stddev-sans-first:0.02
03:51:02 INFO - PID 10757 | Values: 1.7 1.8 1.8 1.8 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.8
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.76 Median:1.77 stddev:0.03 (1.5%) stddev-sans-first:0.03
03:51:02 INFO - PID 10757 | Values: 1.8 1.8 1.7 1.8 1.7 1.7 1.8 1.8 1.8 1.8 1.8 1.8
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.11 Median:4.12 stddev:0.10 (2.3%) stddev-sans-first:0.07
03:51:02 INFO - PID 10757 | Values: 3.9 4.0 4.1 4.1 4.2 4.1 4.1 4.0 4.2 4.1 4.3 4.1
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.11 Median:4.13 stddev:0.10 (2.5%) stddev-sans-first:0.10
03:51:02 INFO - PID 10757 | Values: 4.0 4.1 4.1 4.1 4.1 4.2 3.8 4.2 4.2 4.1 4.1 4.2
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.60 Median:3.60 stddev:0.06 (1.7%) stddev-sans-first:0.06
03:51:02 INFO - PID 10757 | Values: 3.7 3.5 3.6 3.6 3.6 3.7 3.6 3.6 3.7 3.5 3.5 3.6
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.50 Median:3.52 stddev:0.10 (2.7%) stddev-sans-first:0.10
03:51:02 INFO - PID 10757 | Values: 3.4 3.4 3.4 3.5 3.6 3.6 3.6 3.6 3.6 3.4 3.4 3.4
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.64 Median:5.72 stddev:0.32 (5.6%) stddev-sans-first:0.09
03:51:02 INFO - PID 10757 | Values: 4.7 5.7 5.6 5.7 5.6 5.7 5.8 5.8 5.8 5.7 5.8 5.9
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.58 Median:5.64 stddev:0.10 (1.9%) stddev-sans-first:0.11
03:51:02 INFO - PID 10757 | Values: 5.6 5.4 5.7 5.5 5.6 5.5 5.6 5.6 5.7 5.7 5.7 5.5
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.90 Median:9.68 stddev:0.29 (3.0%) stddev-sans-first:0.28
03:51:02 INFO - PID 10757 | Values: 9.6 10.0 9.6 9.7 10.5 10.1 10.0 9.4 10.2 9.9 9.9 9.8
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.42 Median:9.43 stddev:0.23 (2.5%) stddev-sans-first:0.24
03:51:02 INFO - PID 10757 | Values: 9.3 9.4 9.1 9.5 9.4 9.4 9.7 9.2 9.4 9.1 9.4 10.0
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.38 Median:11.39 stddev:0.11 (1.0%) stddev-sans-first:0.12
03:51:02 INFO - PID 10757 | Values: 11.4 11.2 11.4 11.4 11.6 11.3 11.3 11.3 11.5 11.3 11.5 11.3
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.88 Median:10.90 stddev:0.16 (1.5%) stddev-sans-first:0.17
03:51:02 INFO - PID 10757 | Values: 11.0 10.8 10.7 11.2 11.1 10.8 10.8 10.8 10.7 11.0 11.1 10.8
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.45 Median:12.50 stddev:0.13 (1.1%) stddev-sans-first:0.14
03:51:02 INFO - PID 10757 | Values: 12.3 12.3 12.5 12.7 12.4 12.2 12.5 12.5 12.3 12.6 12.6 12.5
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.90 Median:11.91 stddev:0.13 (1.1%) stddev-sans-first:0.12
03:51:02 INFO - PID 10757 | Values: 11.7 11.8 11.9 12.0 11.8 11.8 11.9 11.8 12.2 12.0 11.8 12.1
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.53 Median:11.54 stddev:0.13 (1.1%) stddev-sans-first:0.13
03:51:02 INFO - PID 10757 | Values: 11.5 11.7 11.7 11.5 11.6 11.5 11.6 11.4 11.4 11.5 11.5 11.3
03:51:02 INFO - PID 10757 |
03:51:02 INFO - PID 10757 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.97 Median:11.02 stddev:0.19 (1.7%) stddev-sans-first:0.20
03:51:02 INFO - PID 10757 | Values: 10.9 11.1 11.1 10.9 11.2 10.9 11.1 10.6 11.0 11.0 11.2 10.7
03:51:02 INFO - PID 10757 | -------- Summary: end --------
03:51:02 INFO - PID 10757 |
03:51:03 INFO - TEST-INFO | 10757: exit 0
03:51:03 INFO - TEST-OK | basic_compositor_video | took 561295ms
03:51:03 INFO - TEST-START | glvideo
03:51:03 INFO - Initialising browser for glvideo test...
03:51:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59636/getInfo.html -profile /tmp/tmpttitfe/profile
03:51:03 INFO - TEST-INFO | started process 11999 (/builds/slave/test/build/application/firefox/firefox http://localhost:59636/getInfo.html)
03:51:08 INFO - TEST-INFO | 11999: exit 0
03:51:08 INFO - Browser initialized.
03:51:08 INFO - Running cycle 1/1 for glvideo test...
03:51:08 INFO - Using env: {'DISPLAY': ':0',
03:51:08 INFO - 'HOME': '/home/cltbld',
03:51:08 INFO - 'JSGC_DISABLE_POISONING': '1',
03:51:08 INFO - 'LANG': 'en_US.UTF-8',
03:51:08 INFO - 'LANGUAGE': 'en_US:en',
03:51:08 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:51:08 INFO - 'LOGNAME': 'cltbld',
03:51:08 INFO - 'MAIL': '/var/mail/cltbld',
03:51:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:51:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:51:08 INFO - 'MOZ_CRASHREPORTER': '1',
03:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:51:08 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:51:08 INFO - 'MOZ_NO_REMOTE': '1',
03:51:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:51:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:51:08 INFO - 'NO_EM_RESTART': '1',
03:51:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:51:08 INFO - 'PWD': '/builds/slave/test',
03:51:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:51:08 INFO - 'RUST_BACKTRACE': 'full',
03:51:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:51:08 INFO - 'SHELL': '/bin/bash',
03:51:08 INFO - 'SHLVL': '1',
03:51:08 INFO - 'STYLO_FORCE_DISABLED': '1',
03:51:08 INFO - 'TERM': 'linux',
03:51:08 INFO - 'TMOUT': '86400',
03:51:08 INFO - 'USER': 'cltbld',
03:51:08 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475',
03:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:51:08 INFO - '_': '/tools/buildbot/bin/python'}
03:51:08 INFO - TEST-INFO | started process 12263 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpttitfe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:51:09 INFO - PID 12263 |
03:51:09 INFO - PID 12263 | (/builds/slave/test/build/application/firefox/firefox:12314): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:51:09 INFO - PID 12263 |
03:51:10 INFO - PID 12263 | [talos glvideo result] Mean tick time across 100 ticks: 6.770899999999999 ms
03:51:10 INFO - PID 12263 | Cycle 1(1): loaded http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html)
03:51:12 INFO - PID 12263 | [talos glvideo result] Mean tick time across 100 ticks: 6.645449999999999 ms
03:51:12 INFO - PID 12263 | Cycle 1(2): loaded http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html)
03:51:13 INFO - PID 12263 | [talos glvideo result] Mean tick time across 100 ticks: 6.64105 ms
03:51:13 INFO - PID 12263 | Cycle 1(3): loaded http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html)
03:51:14 INFO - PID 12263 | [talos glvideo result] Mean tick time across 100 ticks: 6.538250000000001 ms
03:51:14 INFO - PID 12263 | Cycle 1(4): loaded http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html)
03:51:16 INFO - PID 12263 | [talos glvideo result] Mean tick time across 100 ticks: 6.535 ms
03:51:16 INFO - PID 12263 | Cycle 1(5): loaded http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59636/tests/webgl/benchmarks/video/video_upload.html)
03:51:16 INFO - PID 12263 | __start_tp_report
03:51:16 INFO - PID 12263 | _x_x_mozilla_page_load
03:51:16 INFO - PID 12263 | _x_x_mozilla_page_load_details
03:51:16 INFO - PID 12263 | |i|pagename|runs|
03:51:16 INFO - PID 12263 | |0;Mean tick time across 100 ticks: ;6.770899999999999;6.645449999999999;6.64105;6.538250000000001;6.535
03:51:16 INFO - PID 12263 | __end_tp_report
03:51:16 INFO - PID 12263 | __start_cc_report
03:51:16 INFO - PID 12263 | _x_x_mozilla_cycle_collect,286
03:51:16 INFO - PID 12263 | __end_cc_report
03:51:16 INFO - PID 12263 | __startTimestamp1508496676474__endTimestamp
03:51:16 INFO - PID 12263 |
03:51:16 INFO - PID 12263 | ------- Summary: start -------
03:51:16 INFO - PID 12263 | Number of tests: 1
03:51:16 INFO - PID 12263 |
03:51:16 INFO - PID 12263 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.63 Median:6.64 stddev:0.10 (1.5%) stddev-sans-first:0.06
03:51:16 INFO - PID 12263 | Values: 6.8 6.6 6.6 6.5 6.5
03:51:16 INFO - PID 12263 | -------- Summary: end --------
03:51:16 INFO - PID 12263 |
03:51:16 INFO - PID 12263 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:51:17 INFO - TEST-INFO | 12263: exit 0
03:51:17 INFO - TEST-OK | glvideo | took 13909ms
03:51:17 INFO - SUITE-END | took 575s
03:51:17 INFO - Completed test suite (00:09:35)
03:51:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.598151016388925, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6876490438695164, 1.6801595744680853, 1.6866160764474425, 1.6819226457399103, 1.696455624646693, 1.684102132435466, 1.6822029147982063, 1.6838215488215489, 1.6866273187183813, 1.6875337457817772, 1.6789983212087296, 1.6906929577464789], "value": 1.684102132435466, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6700292153589313, 1.6752847571189275, 1.6731312709030097, 1.6670208333333334, 1.6682944120100085, 1.6710568086883877, 1.6743640167364018, 1.6702253756260432, 1.669874791318865, 1.6756448911222783, 1.6691659716430363, 1.6669291666666668], "value": 1.6702253756260432, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.744776162790698, 1.7560415447630198, 1.7597653958944284, 1.7914059701492535, 1.7497055393586005, 1.7681820860341777, 1.792574671445639, 1.809650180940893, 1.7806290801186948, 1.8099517490952952, 1.7838079667063025, 1.7816211401425177], "value": 1.7816211401425177, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7653751103265667, 1.753400525854513, 1.7114841745081268, 1.7767406749555956, 1.73627170138889, 1.7324632034632033, 1.7595954265611256, 1.7723560673162095, 1.7876764968722072, 1.7794617437722418, 1.792809139784946, 1.7943452914798204], "value": 1.7723560673162095, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.8921011673151744, 4.0442991913746615, 4.09974043715847, 4.138462068965517, 4.220042194092826, 4.0763722826086966, 4.121407967032967, 4.049730094466937, 4.202822128851541, 4.110931506849314, 4.262109375, 4.099323770491803], "value": 4.110931506849314, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.016024096385541, 4.099795081967215, 4.149885892116184, 4.091196319018404, 4.090817995910024, 4.2200316455696205, 3.8468461538461516, 4.211136842105261, 4.150217842323649, 4.090265848670759, 4.115925925925926, 4.2305391120507405], "value": 4.115925925925926, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.6816503067484687, 3.517831184056273, 3.5633135391924005, 3.559875444839858, 3.5812171837708826, 3.672399020807832, 3.628065296251509, 3.6369090909090898, 3.6865909090909117, 3.5302058823529405, 3.521813380281689, 3.5642042755344434], "value": 3.5642042755344434, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4258818493150627, 3.3965534804753816, 3.408943781942077, 3.468093587521664, 3.5913913824057495, 3.6245380434782657, 3.630580762250455, 3.566149732620322, 3.6051531531531475, 3.4251541095890428, 3.4130972696245747, 3.432092624356775], "value": 3.468093587521664, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.667161741835149, 5.651666666666666, 5.63062851782364, 5.683844696969697, 5.628827392120077, 5.683115530303026, 5.814467054263572, 5.792789575289577, 5.792258687258693, 5.650047080979283, 5.759309021113248, 5.907224409448817], "value": 5.683844696969697, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.635704225352111, 5.391954177897577, 5.652344632768362, 5.512272727272732, 5.63684507042253, 5.465710382513663, 5.572325905292479, 5.572743732590534, 5.68311079545454, 5.749051724137937, 5.667549575070823, 5.48015068493151], "value": 5.572743732590534, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.585591054313102, 10.036638795986615, 9.618285256410253, 9.742272727272724, 10.456341463414638, 10.06802013422819, 9.967906976744187, 9.435408805031443, 10.242030716723553, 9.901056105610563, 9.870592105263157, 9.837819672131147], "value": 9.901056105610563, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.349906542056079, 9.350607476635526, 9.094204545454556, 9.524952380952389, 9.4341037735849, 9.437429245283022, 9.666376811594214, 9.221774193548388, 9.393403755868553, 9.136050228310511, 9.434245283018857, 9.95519900497512], "value": 9.4341037735849, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.409961977186311, 11.1957462686567, 11.363655303030306, 11.408840304182515, 11.628527131782946, 11.322962264150954, 11.32418867924527, 11.322905660377357, 11.499118773946362, 11.323113207547156, 11.451106870229003, 11.280075187969924], "value": 11.32418867924527, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.989917582417588, 10.753413978494635, 10.699331550802146, 11.177150837988822, 11.050027624309394, 10.81470270270267, 10.757043010752716, 10.755322580645151, 10.695401069518722, 10.992637362637382, 11.050165745856363, 10.81243243243241], "value": 10.81243243243241, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.347304526748989, 12.348683127572029, 12.500833333333352, 12.716186440677953, 12.39818181818182, 12.195691056910567, 12.452095435684654, 12.50147916666665, 12.348004115226347, 12.553263598326373, 12.555857740585768, 12.500437500000013], "value": 12.500437500000013, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.698771929824549, 11.769205882352939, 11.908333333333324, 11.975778443113782, 11.837011834319537, 11.837218934911274, 11.911547619047615, 11.840266272189377, 12.198231707317042, 11.978143712574841, 11.83964497041421, 12.051536144578314], "value": 11.908333333333324, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.454236641221382, 11.72121093749999, 11.722480468749978, 11.454217557251926, 11.630988372093016, 11.497164750957863, 11.62986434108529, 11.409144486692027, 11.40880228136882, 11.540576923076934, 11.541288461538464, 11.322490566037732], "value": 11.540576923076934, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.932759562841529, 11.111583333333328, 11.11669444444447, 10.87119565217389, 11.238398876404482, 10.873668478260855, 11.051823204419875, 10.584338624338637, 10.991263736263756, 10.989972527472503, 11.17670391061455, 10.698315508021386], "value": 10.991263736263756, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.770899999999999, 6.645449999999999, 6.64105, 6.538250000000001, 6.535], "value": 6.589650000000001, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
03:51:17 INFO - Return code: 0
03:51:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:51:17 INFO - # TBPL SUCCESS #
03:51:17 INFO - Running post-action listener: _package_coverage_data
03:51:17 INFO - Running post-action listener: _resource_record_post_action
03:51:17 INFO - [mozharness: 2017-10-20 10:51:17.630532Z] Finished run-tests step (success)
03:51:17 INFO - Running post-run listener: _resource_record_post_run
03:51:17 INFO - Total resource usage - Wall time: 595s; CPU: 39.0%; Read bytes: 147456; Write bytes: 441339904; Read time: 476; Write time: 450612
03:51:17 INFO - TinderboxPrint: CPU usage
38.7%
03:51:17 INFO - TinderboxPrint: I/O read bytes / time
147,456 / 476
03:51:17 INFO - TinderboxPrint: I/O write bytes / time
441,339,904 / 450,612
03:51:17 INFO - TinderboxPrint: CPU idle
2,860.2 (61.2%)
03:51:17 INFO - TinderboxPrint: CPU system
301.9 (6.5%)
03:51:17 INFO - TinderboxPrint: CPU user
1,492.3 (32.0%)
03:51:17 INFO - TinderboxPrint: Swap in / out
0 / 0
03:51:17 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 4096; Write bytes: 129593344; Read time: 224; Write time: 136472
03:51:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:51:17 INFO - run-tests - Wall time: 576s; CPU: 40.0%; Read bytes: 143360; Write bytes: 311746560; Read time: 252; Write time: 314140
03:51:17 INFO - Running post-run listener: _upload_blobber_files
03:51:17 INFO - Blob upload gear active.
03:51:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:51:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
03:51:17 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
03:51:17 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
03:51:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:334: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:18 INFO - SNIMissingWarning
03:51:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:18 INFO - InsecurePlatformWarning
03:51:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:51:18 INFO - SubjectAltNameWarning
03:51:18 INFO - (blobuploader) - INFO - Open directory for files ...
03:51:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
03:51:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:18 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:18 INFO - InsecurePlatformWarning
03:51:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:51:18 INFO - SubjectAltNameWarning
03:51:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:18 INFO - InsecurePlatformWarning
03:51:19 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
03:51:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:19 INFO - (blobuploader) - INFO - Done attempting.
03:51:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:51:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:19 INFO - InsecurePlatformWarning
03:51:19 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:51:19 INFO - SubjectAltNameWarning
03:51:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:24 INFO - InsecurePlatformWarning
03:51:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:51:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:24 INFO - (blobuploader) - INFO - Done attempting.
03:51:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
03:51:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:24 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:24 INFO - InsecurePlatformWarning
03:51:24 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:51:24 INFO - SubjectAltNameWarning
03:51:25 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:25 INFO - InsecurePlatformWarning
03:51:25 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
03:51:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:25 INFO - (blobuploader) - INFO - Done attempting.
03:51:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:51:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:51:25 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:51:25 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:25 INFO - InsecurePlatformWarning
03:51:25 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:51:25 INFO - SubjectAltNameWarning
03:51:25 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:51:25 INFO - InsecurePlatformWarning
03:51:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:51:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:51:26 INFO - (blobuploader) - INFO - Done attempting.
03:51:26 INFO - (blobuploader) - INFO - Iteration through files over.
03:51:26 INFO - Return code: 0
03:51:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:51:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:51:26 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ecc0095f160d34900dc2b5004947e788d08974b82b8f502182d01f81d754a7f2ec9af087d525511fd389b81fd188eeacd16b53e7893af8812b3953cc83017d0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e539e3df7b08f59f577045149761aaa86534e4ba0ae2e7f0f763e416c215590cfb9540bfb3141a67395de50ad14322b1493ac3bf1d779dd8c2170c0cba4e3a8c", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f880aef5d6427d2c97d80c28e39d91c79590cab400a7ccb2606a2efeedb7aba0aae8e7ba91027d9ffadc2546011950b02c495994136e5a0a81f1deb305b85d0", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5c41b215d87b575284c0b6db4ce29842231d719e66ca5a0cfe118fb382da27c40f951ba46244f62e2126ea3803853db966eb0026a5752203b65adaba1421947"}
03:51:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:51:26 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:51:26 INFO - Contents:
03:51:26 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ecc0095f160d34900dc2b5004947e788d08974b82b8f502182d01f81d754a7f2ec9af087d525511fd389b81fd188eeacd16b53e7893af8812b3953cc83017d0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e539e3df7b08f59f577045149761aaa86534e4ba0ae2e7f0f763e416c215590cfb9540bfb3141a67395de50ad14322b1493ac3bf1d779dd8c2170c0cba4e3a8c", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f880aef5d6427d2c97d80c28e39d91c79590cab400a7ccb2606a2efeedb7aba0aae8e7ba91027d9ffadc2546011950b02c495994136e5a0a81f1deb305b85d0", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5c41b215d87b575284c0b6db4ce29842231d719e66ca5a0cfe118fb382da27c40f951ba46244f62e2126ea3803853db966eb0026a5752203b65adaba1421947"}
03:51:26 INFO - Running post-run listener: copy_logs_to_upload_dir
03:51:26 INFO - Copying logs to upload dir...
03:51:26 INFO - mkdir: /builds/slave/test/build/upload/logs
03:51:26 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=654.961403
========= master_lag: 0.51 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 55 secs) (at 2017-10-20 03:51:26.726933) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-20 03:51:26.730529) =========
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=41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475
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-Non-PGO/sha512/9ecc0095f160d34900dc2b5004947e788d08974b82b8f502182d01f81d754a7f2ec9af087d525511fd389b81fd188eeacd16b53e7893af8812b3953cc83017d0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e539e3df7b08f59f577045149761aaa86534e4ba0ae2e7f0f763e416c215590cfb9540bfb3141a67395de50ad14322b1493ac3bf1d779dd8c2170c0cba4e3a8c", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f880aef5d6427d2c97d80c28e39d91c79590cab400a7ccb2606a2efeedb7aba0aae8e7ba91027d9ffadc2546011950b02c495994136e5a0a81f1deb305b85d0", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5c41b215d87b575284c0b6db4ce29842231d719e66ca5a0cfe118fb382da27c40f951ba46244f62e2126ea3803853db966eb0026a5752203b65adaba1421947"}
build_url:https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011274
build_url: 'https://queue.taskcluster.net/v1/task/ebeSnEKyS2CT1S2m5atBaA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9ecc0095f160d34900dc2b5004947e788d08974b82b8f502182d01f81d754a7f2ec9af087d525511fd389b81fd188eeacd16b53e7893af8812b3953cc83017d0", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e539e3df7b08f59f577045149761aaa86534e4ba0ae2e7f0f763e416c215590cfb9540bfb3141a67395de50ad14322b1493ac3bf1d779dd8c2170c0cba4e3a8c", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8f880aef5d6427d2c97d80c28e39d91c79590cab400a7ccb2606a2efeedb7aba0aae8e7ba91027d9ffadc2546011950b02c495994136e5a0a81f1deb305b85d0", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d5c41b215d87b575284c0b6db4ce29842231d719e66ca5a0cfe118fb382da27c40f951ba46244f62e2126ea3803853db966eb0026a5752203b65adaba1421947"}'
========= master_lag: 0.11 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-20 03:51:26.849018) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-20 03:51:26.849359) =========
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=41bf3e9aed84707956354da8000001c5-1508495721.108348-1537939475
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004490
========= master_lag: 0.65 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-20 03:51:27.499098) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-20 03:51:27.499657) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-20 03:51:27.599604) =========
========= Total master_lag: 1.35 =========