builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s
slave: talos-linux64-ix-031
starttime: 1511383121.75
results: success (0)
revision: 329fb4d184615224539b1bb578e64e39f68a4836
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.750897) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.751595) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.752016) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.772229) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.772630) =========
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=db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-22 12:38:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 222M=0s
2017-11-22 12:38:41 (222 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.128684
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.917202) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.917604) =========
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=db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.022295
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:41.967461) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-22 12:38:41.967826) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 329fb4d184615224539b1bb578e64e39f68a4836 --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 329fb4d184615224539b1bb578e64e39f68a4836 --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=db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-22 12:38:42,021 truncating revision to first 12 chars
2017-11-22 12:38:42,021 Setting DEBUG logging.
2017-11-22 12:38:42,021 attempt 1/10
2017-11-22 12:38:42,021 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/329fb4d18461?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-22 12:38:43,323 unpacking tar archive at: mozilla-central-329fb4d18461/testing/mozharness/
program finished with exit code 0
elapsedTime=1.923046
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-22 12:38:43.912064) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:43.912395) =========
script_repo_revision: 329fb4d184615224539b1bb578e64e39f68a4836
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:43.912868) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:43.913129) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-22 12:38:43.928811) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 56 secs) (at 2017-11-22 12:38:43.929138) =========
/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=db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
12:38:44 INFO - MultiFileLogger online at 20171122 12:38:44 in /builds/slave/test
12:38:44 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
12:38:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
12:38:44 INFO - {'append_to_log': False,
12:38:44 INFO - 'base_work_dir': '/builds/slave/test',
12:38:44 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
12:38:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
12:38:44 INFO - 'branch': 'Firefox-Non-PGO',
12:38:44 INFO - 'buildbot_json_path': 'buildprops.json',
12:38:44 INFO - 'code_coverage': False,
12:38:44 INFO - 'config_files': ('talos/linux_config.py',),
12:38:44 INFO - 'default_actions': ('clobber',
12:38:44 INFO - 'read-buildbot-config',
12:38:44 INFO - 'download-and-extract',
12:38:44 INFO - 'populate-webroot',
12:38:44 INFO - 'create-virtualenv',
12:38:44 INFO - 'install',
12:38:44 INFO - 'setup-mitmproxy',
12:38:44 INFO - 'run-tests'),
12:38:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:38:44 INFO - 'disable_ccov_upload': False,
12:38:44 INFO - 'disable_stylo': False,
12:38:44 INFO - 'download_minidump_stackwalk': True,
12:38:44 INFO - 'download_symbols': 'ondemand',
12:38:44 INFO - 'e10s': False,
12:38:44 INFO - 'enable_stylo': False,
12:38:44 INFO - 'enable_webrender': False,
12:38:44 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
12:38:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:38:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:38:44 INFO - 'gecko_profile': False,
12:38:44 INFO - 'gecko_profile_interval': 0,
12:38:44 INFO - 'installer_path': 'installer.exe',
12:38:44 INFO - 'jsd_code_coverage': False,
12:38:44 INFO - 'log_level': 'info',
12:38:44 INFO - 'log_name': 'talos',
12:38:44 INFO - 'log_to_console': True,
12:38:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
12:38:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
12:38:44 INFO - 'opt_config_files': (),
12:38:44 INFO - 'pip_index': False,
12:38:44 INFO - 'suite': 'g4-stylo-disabled-e10s',
12:38:44 INFO - 'system_bits': '32',
12:38:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:38:44 INFO - 'title': 'talos-linux64-ix-031',
12:38:44 INFO - 'tooltool_cache': '/builds/tooltool_cache',
12:38:44 INFO - 'use_talos_json': True,
12:38:44 INFO - 'verify': 'False',
12:38:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
12:38:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:38:44 INFO - 'work_dir': 'build'}
12:38:44 INFO - [mozharness: 2017-11-22 20:38:44.110454Z] Running clobber step.
12:38:44 INFO - Running pre-action listener: _resource_record_pre_action
12:38:44 INFO - Running main action method: clobber
12:38:44 INFO - rmtree: /builds/slave/test/build
12:38:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
12:38:45 INFO - Running post-action listener: _resource_record_post_action
12:38:45 INFO - [mozharness: 2017-11-22 20:38:45.214005Z] Finished clobber step (success)
12:38:45 INFO - [mozharness: 2017-11-22 20:38:45.214101Z] Running read-buildbot-config step.
12:38:45 INFO - Running pre-action listener: _resource_record_pre_action
12:38:45 INFO - Running main action method: read_buildbot_config
12:38:45 INFO - Using buildbot properties:
12:38:45 INFO - {
12:38:45 INFO - "project": "",
12:38:45 INFO - "product": "firefox",
12:38:45 INFO - "who": "aklotz@mozilla.com",
12:38:45 INFO - "installer_path": "public/build/target.tar.bz2",
12:38:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
12:38:45 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
12:38:45 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-stylo-disabled-e10s",
12:38:45 INFO - "stage_platform": "linux64",
12:38:45 INFO - "basedir": "/builds/slave/test",
12:38:45 INFO - "buildnumber": 209,
12:38:45 INFO - "platform": "ubuntu64_hw",
12:38:45 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
12:38:45 INFO - "slavebuilddir": "test",
12:38:45 INFO - "taskId": "OnnmGiUyRb6FibMH_CF96A",
12:38:45 INFO - "branch": "mozilla-central",
12:38:45 INFO - "script_repo_revision": "production",
12:38:45 INFO - "revision": "329fb4d184615224539b1bb578e64e39f68a4836",
12:38:45 INFO - "slavename": "talos-linux64-ix-031",
12:38:45 INFO - "repo_path": "mozilla-central"
12:38:45 INFO - }
12:38:45 INFO - Finding installer, test and symbols from parent task.
12:38:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OnnmGiUyRb6FibMH_CF96A'}, attempt #1
12:38:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OnnmGiUyRb6FibMH_CF96A'}, attempt #1
12:38:46 INFO - Task dependencies: CDTDanMpR8mtMOODEcCVyQ
12:38:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ'}, attempt #1
12:38:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2
12:38:46 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.test_packages.json
12:38:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.crashreporter-symbols.zip
12:38:46 INFO - Running post-action listener: _resource_record_post_action
12:38:46 INFO - [mozharness: 2017-11-22 20:38:46.571587Z] Finished read-buildbot-config step (success)
12:38:46 INFO - [mozharness: 2017-11-22 20:38:46.571803Z] Running download-and-extract step.
12:38:46 INFO - Running pre-action listener: _resource_record_pre_action
12:38:46 INFO - Running main action method: download_and_extract
12:38:46 INFO - mkdir: /builds/slave/test/build/tests
12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:38:46 INFO - trying https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.test_packages.json
12:38:46 INFO - Downloading https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
12:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
12:38:47 INFO - Downloaded 989 bytes.
12:38:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
12:38:47 INFO - Using the following test package requirements:
12:38:47 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
12:38:47 INFO - u'common': [u'target.common.tests.zip'],
12:38:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
12:38:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
12:38:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
12:38:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
12:38:47 INFO - u'mozbase': [u'target.common.tests.zip'],
12:38:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
12:38:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
12:38:47 INFO - u'web-platform': [u'target.common.tests.zip',
12:38:47 INFO - u'target.web-platform.tests.tar.gz'],
12:38:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
12:38:47 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
12:38:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.common.tests.zip
12:38:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
12:38:47 INFO - Fetch https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.common.tests.zip into memory
12:38:48 INFO - Content-Length response header: 59063203
12:38:48 INFO - Bytes received: 59063203
12:38:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.talos.tests.zip
12:38:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
12:38:59 INFO - Fetch https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.talos.tests.zip into memory
12:39:00 INFO - Content-Length response header: 18772503
12:39:00 INFO - Bytes received: 18772503
12:39:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:00 INFO - trying https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2
12:39:00 INFO - Downloading https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
12:39:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
12:39:02 INFO - Downloaded 62560913 bytes.
12:39:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2
12:39:02 INFO - mkdir: /builds/slave/test/properties
12:39:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
12:39:02 INFO - Writing to file /builds/slave/test/properties/build_url
12:39:02 INFO - Contents:
12:39:02 INFO - build_url:https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2
12:39:02 INFO - Running post-action listener: _resource_record_post_action
12:39:02 INFO - Running post-action listener: find_tests_for_verification
12:39:02 INFO - Running post-action listener: set_extra_try_arguments
12:39:02 INFO - [mozharness: 2017-11-22 20:39:02.109990Z] Finished download-and-extract step (success)
12:39:02 INFO - [mozharness: 2017-11-22 20:39:02.110086Z] Running populate-webroot step.
12:39:02 INFO - Running pre-action listener: _resource_record_pre_action
12:39:02 INFO - Running main action method: populate_webroot
12:39:02 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
12:39:02 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['tresize',
12:39:02 INFO - 'tcanvasmark']},
12:39:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
12:39:02 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['dromaeo_css',
12:39:02 INFO - 'kraken']},
12:39:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:39:02 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:39:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'tests': ['damp', 'tps']},
12:39:02 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['damp', 'tps']},
12:39:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
12:39:02 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['dromaeo_dom']},
12:39:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
12:39:02 INFO - 'glvideo',
12:39:02 INFO - 'displaylist_mutate']},
12:39:02 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['basic_compositor_video',
12:39:02 INFO - 'glvideo']},
12:39:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
12:39:02 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['ts_paint_webext',
12:39:02 INFO - 'tp5o_webext']},
12:39:02 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
12:39:02 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:39:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:39:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:39:02 INFO - 'talos_options': ['--mitmproxy',
12:39:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:39:02 INFO - '--firstNonBlankPaint'],
12:39:02 INFO - 'tests': ['tp6_google_heavy',
12:39:02 INFO - 'tp6_youtube_heavy',
12:39:02 INFO - 'tp6_amazon_heavy',
12:39:02 INFO - 'tp6_facebook_heavy']},
12:39:02 INFO - 'other-e10s': {'tests': ['a11yr',
12:39:02 INFO - 'ts_paint',
12:39:02 INFO - 'tpaint',
12:39:02 INFO - 'sessionrestore',
12:39:02 INFO - 'sessionrestore_many_windows',
12:39:02 INFO - 'sessionrestore_no_auto_restore',
12:39:02 INFO - 'tabpaint',
12:39:02 INFO - 'cpstartup']},
12:39:02 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['a11yr',
12:39:02 INFO - 'ts_paint',
12:39:02 INFO - 'tpaint',
12:39:02 INFO - 'sessionrestore',
12:39:02 INFO - 'sessionrestore_many_windows',
12:39:02 INFO - 'sessionrestore_no_auto_restore',
12:39:02 INFO - 'tabpaint',
12:39:02 INFO - 'cpstartup']},
12:39:02 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
12:39:02 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
12:39:02 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['perf_reftest_singletons']},
12:39:02 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['perf_reftest']},
12:39:02 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
12:39:02 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['speedometer']},
12:39:02 INFO - 'svgr-e10s': {'tests': ['tsvgx',
12:39:02 INFO - 'tsvgr_opacity',
12:39:02 INFO - 'tart',
12:39:02 INFO - 'tscrollx',
12:39:02 INFO - 'tsvg_static']},
12:39:02 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['tsvgx',
12:39:02 INFO - 'tsvgr_opacity',
12:39:02 INFO - 'tart',
12:39:02 INFO - 'tscrollx',
12:39:02 INFO - 'tsvg_static']},
12:39:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
12:39:02 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'talos_options': ['--disable-stylo'],
12:39:02 INFO - 'tests': ['tp5o']},
12:39:02 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:39:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:39:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:39:02 INFO - 'talos_options': ['--mitmproxy',
12:39:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:39:02 INFO - '--firstNonBlankPaint'],
12:39:02 INFO - 'tests': ['tp6_google',
12:39:02 INFO - 'tp6_youtube',
12:39:02 INFO - 'tp6_amazon',
12:39:02 INFO - 'tp6_facebook']},
12:39:02 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:39:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:39:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:39:02 INFO - 'talos_options': ['--disable-stylo',
12:39:02 INFO - '--mitmproxy',
12:39:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:39:02 INFO - '--firstNonBlankPaint'],
12:39:02 INFO - 'tests': ['tp6_google',
12:39:02 INFO - 'tp6_youtube',
12:39:02 INFO - 'tp6_amazon',
12:39:02 INFO - 'tp6_facebook']},
12:39:02 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:39:02 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:39:02 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:39:02 INFO - 'talos_options': ['--stylo-threads=1',
12:39:02 INFO - '--mitmproxy',
12:39:02 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:39:02 INFO - '--firstNonBlankPaint'],
12:39:02 INFO - 'tests': ['tp6_google',
12:39:02 INFO - 'tp6_youtube',
12:39:02 INFO - 'tp6_amazon',
12:39:02 INFO - 'tp6_facebook']},
12:39:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'talos_options': ['--xperf_path',
12:39:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:39:02 INFO - 'tests': ['tp5n']},
12:39:02 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:39:02 INFO - 'talos_options': ['--disable-stylo',
12:39:02 INFO - '--xperf_path',
12:39:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:39:02 INFO - 'tests': ['tp5n']}}}
12:39:02 INFO - Running post-action listener: _resource_record_post_action
12:39:02 INFO - [mozharness: 2017-11-22 20:39:02.129103Z] Finished populate-webroot step (success)
12:39:02 INFO - [mozharness: 2017-11-22 20:39:02.129196Z] Running create-virtualenv step.
12:39:02 INFO - Running pre-action listener: _resource_record_pre_action
12:39:02 INFO - Running main action method: create_virtualenv
12:39:02 INFO - Creating virtualenv /builds/slave/test/build/venv
12:39:02 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
12:39:02 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
12:39:02 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
12:39:02 INFO - Using real prefix '/usr'
12:39:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python
12:39:02 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
12:39:04 INFO - Installing setuptools, pip, wheel...done.
12:39:04 INFO - Return code: 0
12:39:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
12:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:04 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
12:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
12:39:04 INFO - Using env: {'DISPLAY': ':0',
12:39:04 INFO - 'HOME': '/home/cltbld',
12:39:04 INFO - 'LANG': 'en_US.UTF-8',
12:39:04 INFO - 'LANGUAGE': 'en_US:en',
12:39:04 INFO - 'LOGNAME': 'cltbld',
12:39:04 INFO - 'MAIL': '/var/mail/cltbld',
12:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:04 INFO - 'MOZ_NO_REMOTE': '1',
12:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:04 INFO - 'NO_EM_RESTART': '1',
12:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:04 INFO - 'PWD': '/builds/slave/test',
12:39:04 INFO - 'SHELL': '/bin/bash',
12:39:04 INFO - 'SHLVL': '1',
12:39:04 INFO - 'TERM': 'linux',
12:39:04 INFO - 'TMOUT': '86400',
12:39:04 INFO - 'USER': 'cltbld',
12:39:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:04 INFO - '_': '/tools/buildbot/bin/python'}
12:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
12:39:04 INFO - Return code: 0
12:39:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
12:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:04 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
12:39:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
12:39:04 INFO - Using env: {'DISPLAY': ':0',
12:39:04 INFO - 'HOME': '/home/cltbld',
12:39:04 INFO - 'LANG': 'en_US.UTF-8',
12:39:04 INFO - 'LANGUAGE': 'en_US:en',
12:39:04 INFO - 'LOGNAME': 'cltbld',
12:39:04 INFO - 'MAIL': '/var/mail/cltbld',
12:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:04 INFO - 'MOZ_NO_REMOTE': '1',
12:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:04 INFO - 'NO_EM_RESTART': '1',
12:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:04 INFO - 'PWD': '/builds/slave/test',
12:39:04 INFO - 'SHELL': '/bin/bash',
12:39:04 INFO - 'SHLVL': '1',
12:39:04 INFO - 'TERM': 'linux',
12:39:04 INFO - 'TMOUT': '86400',
12:39:04 INFO - 'USER': 'cltbld',
12:39:04 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:04 INFO - '_': '/tools/buildbot/bin/python'}
12:39:05 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:05 INFO - Collecting psutil>=3.1.1
12:39:06 INFO - Installing collected packages: psutil
12:39:06 INFO - Successfully installed psutil-3.1.1
12:39:06 INFO - Return code: 0
12:39:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
12:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:06 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
12:39:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
12:39:06 INFO - Using env: {'DISPLAY': ':0',
12:39:06 INFO - 'HOME': '/home/cltbld',
12:39:06 INFO - 'LANG': 'en_US.UTF-8',
12:39:06 INFO - 'LANGUAGE': 'en_US:en',
12:39:06 INFO - 'LOGNAME': 'cltbld',
12:39:06 INFO - 'MAIL': '/var/mail/cltbld',
12:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:06 INFO - 'MOZ_NO_REMOTE': '1',
12:39:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:06 INFO - 'NO_EM_RESTART': '1',
12:39:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:06 INFO - 'PWD': '/builds/slave/test',
12:39:06 INFO - 'SHELL': '/bin/bash',
12:39:06 INFO - 'SHLVL': '1',
12:39:06 INFO - 'TERM': 'linux',
12:39:06 INFO - 'TMOUT': '86400',
12:39:06 INFO - 'USER': 'cltbld',
12:39:06 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:06 INFO - '_': '/tools/buildbot/bin/python'}
12:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:06 INFO - Collecting mozsystemmonitor==0.3
12:39:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
12:39:07 INFO - Installing collected packages: mozsystemmonitor
12:39:07 INFO - Successfully installed mozsystemmonitor-0.3
12:39:07 INFO - Return code: 0
12:39:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
12:39:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:07 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
12:39:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
12:39:07 INFO - Using env: {'DISPLAY': ':0',
12:39:07 INFO - 'HOME': '/home/cltbld',
12:39:07 INFO - 'LANG': 'en_US.UTF-8',
12:39:07 INFO - 'LANGUAGE': 'en_US:en',
12:39:07 INFO - 'LOGNAME': 'cltbld',
12:39:07 INFO - 'MAIL': '/var/mail/cltbld',
12:39:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:07 INFO - 'MOZ_NO_REMOTE': '1',
12:39:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:07 INFO - 'NO_EM_RESTART': '1',
12:39:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:07 INFO - 'PWD': '/builds/slave/test',
12:39:07 INFO - 'SHELL': '/bin/bash',
12:39:07 INFO - 'SHLVL': '1',
12:39:07 INFO - 'TERM': 'linux',
12:39:07 INFO - 'TMOUT': '86400',
12:39:07 INFO - 'USER': 'cltbld',
12:39:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:07 INFO - '_': '/tools/buildbot/bin/python'}
12:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:08 INFO - Collecting jsonschema==2.5.1
12:39:09 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
12:39:09 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
12:39:11 INFO - Installing collected packages: functools32, jsonschema
12:39:11 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
12:39:11 INFO - Return code: 0
12:39:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
12:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:11 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
12:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
12:39:11 INFO - Using env: {'DISPLAY': ':0',
12:39:11 INFO - 'HOME': '/home/cltbld',
12:39:11 INFO - 'LANG': 'en_US.UTF-8',
12:39:11 INFO - 'LANGUAGE': 'en_US:en',
12:39:11 INFO - 'LOGNAME': 'cltbld',
12:39:11 INFO - 'MAIL': '/var/mail/cltbld',
12:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:11 INFO - 'MOZ_NO_REMOTE': '1',
12:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:11 INFO - 'NO_EM_RESTART': '1',
12:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:11 INFO - 'PWD': '/builds/slave/test',
12:39:11 INFO - 'SHELL': '/bin/bash',
12:39:11 INFO - 'SHLVL': '1',
12:39:11 INFO - 'TERM': 'linux',
12:39:11 INFO - 'TMOUT': '86400',
12:39:11 INFO - 'USER': 'cltbld',
12:39:11 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:11 INFO - '_': '/tools/buildbot/bin/python'}
12:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
12:39:12 INFO - Return code: 0
12:39:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
12:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:12 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
12:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
12:39:12 INFO - Using env: {'DISPLAY': ':0',
12:39:12 INFO - 'HOME': '/home/cltbld',
12:39:12 INFO - 'LANG': 'en_US.UTF-8',
12:39:12 INFO - 'LANGUAGE': 'en_US:en',
12:39:12 INFO - 'LOGNAME': 'cltbld',
12:39:12 INFO - 'MAIL': '/var/mail/cltbld',
12:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:12 INFO - 'MOZ_NO_REMOTE': '1',
12:39:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:12 INFO - 'NO_EM_RESTART': '1',
12:39:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:12 INFO - 'PWD': '/builds/slave/test',
12:39:12 INFO - 'SHELL': '/bin/bash',
12:39:12 INFO - 'SHLVL': '1',
12:39:12 INFO - 'TERM': 'linux',
12:39:12 INFO - 'TMOUT': '86400',
12:39:12 INFO - 'USER': 'cltbld',
12:39:12 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:12 INFO - '_': '/tools/buildbot/bin/python'}
12:39:12 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:12 INFO - Collecting blobuploader==1.2.4
12:39:13 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
12:39:14 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
12:39:15 INFO - Installing collected packages: requests, docopt, blobuploader
12:39:16 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
12:39:16 INFO - Return code: 0
12:39:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:16 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
12:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:39:16 INFO - Using env: {'DISPLAY': ':0',
12:39:16 INFO - 'HOME': '/home/cltbld',
12:39:16 INFO - 'LANG': 'en_US.UTF-8',
12:39:16 INFO - 'LANGUAGE': 'en_US:en',
12:39:16 INFO - 'LOGNAME': 'cltbld',
12:39:16 INFO - 'MAIL': '/var/mail/cltbld',
12:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:16 INFO - 'MOZ_NO_REMOTE': '1',
12:39:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:16 INFO - 'NO_EM_RESTART': '1',
12:39:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:16 INFO - 'PWD': '/builds/slave/test',
12:39:16 INFO - 'SHELL': '/bin/bash',
12:39:16 INFO - 'SHLVL': '1',
12:39:16 INFO - 'TERM': 'linux',
12:39:16 INFO - 'TMOUT': '86400',
12:39:16 INFO - 'USER': 'cltbld',
12:39:16 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:16 INFO - '_': '/tools/buildbot/bin/python'}
12:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:16 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:39:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:39:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:39:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:39:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:39:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:39:19 INFO - Running setup.py install for manifestparser: started
12:39:20 INFO - Running setup.py install for manifestparser: finished with status 'done'
12:39:20 INFO - Running setup.py install for mozcrash: started
12:39:20 INFO - Running setup.py install for mozcrash: finished with status 'done'
12:39:20 INFO - Running setup.py install for mozdebug: started
12:39:20 INFO - Running setup.py install for mozdebug: finished with status 'done'
12:39:20 INFO - Running setup.py install for mozdevice: started
12:39:20 INFO - Running setup.py install for mozdevice: finished with status 'done'
12:39:20 INFO - Running setup.py install for mozfile: started
12:39:20 INFO - Running setup.py install for mozfile: finished with status 'done'
12:39:20 INFO - Running setup.py install for mozhttpd: started
12:39:21 INFO - Running setup.py install for mozhttpd: finished with status 'done'
12:39:21 INFO - Running setup.py install for mozinfo: started
12:39:21 INFO - Running setup.py install for mozinfo: finished with status 'done'
12:39:21 INFO - Running setup.py install for mozInstall: started
12:39:21 INFO - Running setup.py install for mozInstall: finished with status 'done'
12:39:21 INFO - Running setup.py install for mozleak: started
12:39:21 INFO - Running setup.py install for mozleak: finished with status 'done'
12:39:21 INFO - Running setup.py install for mozlog: started
12:39:21 INFO - Running setup.py install for mozlog: finished with status 'done'
12:39:22 INFO - Running setup.py install for moznetwork: started
12:39:22 INFO - Running setup.py install for moznetwork: finished with status 'done'
12:39:22 INFO - Running setup.py install for mozprocess: started
12:39:22 INFO - Running setup.py install for mozprocess: finished with status 'done'
12:39:22 INFO - Running setup.py install for mozprofile: started
12:39:22 INFO - Running setup.py install for mozprofile: finished with status 'done'
12:39:22 INFO - Running setup.py install for mozrunner: started
12:39:22 INFO - Running setup.py install for mozrunner: finished with status 'done'
12:39:22 INFO - Running setup.py install for mozscreenshot: started
12:39:23 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
12:39:23 INFO - Running setup.py install for moztest: started
12:39:23 INFO - Running setup.py install for moztest: finished with status 'done'
12:39:23 INFO - Running setup.py install for mozversion: started
12:39:23 INFO - Running setup.py install for mozversion: finished with status 'done'
12:39:23 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
12:39:23 INFO - Return code: 0
12:39:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:23 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
12:39:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:39:23 INFO - Using env: {'DISPLAY': ':0',
12:39:23 INFO - 'HOME': '/home/cltbld',
12:39:23 INFO - 'LANG': 'en_US.UTF-8',
12:39:23 INFO - 'LANGUAGE': 'en_US:en',
12:39:23 INFO - 'LOGNAME': 'cltbld',
12:39:23 INFO - 'MAIL': '/var/mail/cltbld',
12:39:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:23 INFO - 'MOZ_NO_REMOTE': '1',
12:39:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:23 INFO - 'NO_EM_RESTART': '1',
12:39:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:23 INFO - 'PWD': '/builds/slave/test',
12:39:23 INFO - 'SHELL': '/bin/bash',
12:39:23 INFO - 'SHLVL': '1',
12:39:23 INFO - 'TERM': 'linux',
12:39:23 INFO - 'TMOUT': '86400',
12:39:23 INFO - 'USER': 'cltbld',
12:39:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:23 INFO - '_': '/tools/buildbot/bin/python'}
12:39:24 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:39:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:39:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:39:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
12:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:39:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:39:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
12:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:39:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
12:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:39:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
12:39:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
12:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
12:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:39:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
12:39:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
12:39:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:39:27 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))
12:39:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
12:39:27 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
12:39:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:39:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
12:39:27 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:39:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
12:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
12:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
12:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
12:39:28 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:39:28 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:39:30 INFO - Installing collected packages: six, blessings
12:39:30 INFO - Successfully installed blessings-1.6 six-1.10.0
12:39:30 INFO - Return code: 0
12:39:30 INFO - Done creating virtualenv /builds/slave/test/build/venv.
12:39:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:39:30 INFO - Reading from file tmpfile_stdout
12:39:30 INFO - Reading from file tmpfile_stderr
12:39:30 INFO - Current package versions:
12:39:30 INFO - blessings == 1.6
12:39:30 INFO - blobuploader == 1.2.4
12:39:30 INFO - docopt == 0.6.1
12:39:30 INFO - functools32 == 3.2.3.post2
12:39:30 INFO - jsonschema == 2.5.1
12:39:30 INFO - manifestparser == 1.2
12:39:30 INFO - mozInstall == 1.14
12:39:30 INFO - mozcrash == 1.0
12:39:30 INFO - mozdebug == 0.1
12:39:30 INFO - mozdevice == 0.51
12:39:30 INFO - mozfile == 1.2
12:39:30 INFO - mozhttpd == 0.7
12:39:30 INFO - mozinfo == 0.10
12:39:30 INFO - mozleak == 0.1
12:39:30 INFO - mozlog == 3.5
12:39:30 INFO - moznetwork == 0.27
12:39:30 INFO - mozprocess == 0.25
12:39:30 INFO - mozprofile == 0.29
12:39:30 INFO - mozrunner == 6.13
12:39:30 INFO - mozscreenshot == 0.1
12:39:30 INFO - mozsystemmonitor == 0.3
12:39:30 INFO - moztest == 0.8
12:39:30 INFO - mozversion == 1.4
12:39:30 INFO - psutil == 3.1.1
12:39:30 INFO - requests == 1.2.3
12:39:30 INFO - six == 1.10.0
12:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:30 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
12:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
12:39:30 INFO - Using env: {'DISPLAY': ':0',
12:39:30 INFO - 'HOME': '/home/cltbld',
12:39:30 INFO - 'LANG': 'en_US.UTF-8',
12:39:30 INFO - 'LANGUAGE': 'en_US:en',
12:39:30 INFO - 'LOGNAME': 'cltbld',
12:39:30 INFO - 'MAIL': '/var/mail/cltbld',
12:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:30 INFO - 'MOZ_NO_REMOTE': '1',
12:39:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:30 INFO - 'NO_EM_RESTART': '1',
12:39:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:30 INFO - 'PWD': '/builds/slave/test',
12:39:30 INFO - 'SHELL': '/bin/bash',
12:39:30 INFO - 'SHLVL': '1',
12:39:30 INFO - 'TERM': 'linux',
12:39:30 INFO - 'TMOUT': '86400',
12:39:30 INFO - 'USER': 'cltbld',
12:39:30 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:30 INFO - '_': '/tools/buildbot/bin/python'}
12:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
12:39:31 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
12:39:32 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:39:33 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
12:39:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
12:39:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
12:39:33 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
12:39:33 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:39:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
12:39:34 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:39:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
12:39:35 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:39:36 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
12:39:36 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:39:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
12:39:37 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
12:39:37 INFO - Found existing installation: requests 1.2.3
12:39:37 INFO - Uninstalling requests-1.2.3:
12:39:37 INFO - Successfully uninstalled requests-1.2.3
12:39:38 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
12:39:38 INFO - Return code: 0
12:39:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
12:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:39:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:39:38 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 0x24c9d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24a8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264c460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, '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': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
12:39:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
12:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
12:39:38 INFO - Using env: {'DISPLAY': ':0',
12:39:38 INFO - 'HOME': '/home/cltbld',
12:39:38 INFO - 'LANG': 'en_US.UTF-8',
12:39:38 INFO - 'LANGUAGE': 'en_US:en',
12:39:38 INFO - 'LOGNAME': 'cltbld',
12:39:38 INFO - 'MAIL': '/var/mail/cltbld',
12:39:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:38 INFO - 'MOZ_NO_REMOTE': '1',
12:39:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:38 INFO - 'NO_EM_RESTART': '1',
12:39:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:38 INFO - 'PWD': '/builds/slave/test',
12:39:38 INFO - 'SHELL': '/bin/bash',
12:39:38 INFO - 'SHLVL': '1',
12:39:38 INFO - 'TERM': 'linux',
12:39:38 INFO - 'TMOUT': '86400',
12:39:38 INFO - 'USER': 'cltbld',
12:39:38 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:38 INFO - '_': '/tools/buildbot/bin/python'}
12:39:38 INFO - Ignoring indexes: https://pypi.python.org/simple
12:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
12:39:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
12:39:38 INFO - Return code: 0
12:39:38 INFO - Running post-action listener: _resource_record_post_action
12:39:38 INFO - Running post-action listener: _start_resource_monitoring
12:39:38 INFO - Starting resource monitoring.
12:39:38 INFO - [mozharness: 2017-11-22 20:39:38.623763Z] Finished create-virtualenv step (success)
12:39:38 INFO - [mozharness: 2017-11-22 20:39:38.624278Z] Running install step.
12:39:38 INFO - Running pre-action listener: _resource_record_pre_action
12:39:38 INFO - Running main action method: install
12:39:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:39:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:39:39 INFO - Reading from file tmpfile_stdout
12:39:39 INFO - Reading from file tmpfile_stderr
12:39:39 INFO - Detecting whether we're running mozinstall >=1.0...
12:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
12:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
12:39:39 INFO - Reading from file tmpfile_stdout
12:39:39 INFO - Output received:
12:39:39 INFO - Usage: mozinstall [options] installer
12:39:39 INFO - Options:
12:39:39 INFO - -h, --help show this help message and exit
12:39:39 INFO - -d DEST, --destination=DEST
12:39:39 INFO - Directory to install application into. [default:
12:39:39 INFO - "/builds/slave/test"]
12:39:39 INFO - --app=APP Application being installed. [default: firefox]
12:39:39 INFO - mkdir: /builds/slave/test/build/application
12:39:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
12:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
12:39:57 INFO - Reading from file tmpfile_stdout
12:39:57 INFO - Output received:
12:39:57 INFO - /builds/slave/test/build/application/firefox/firefox
12:39:57 INFO - Running post-action listener: _resource_record_post_action
12:39:57 INFO - [mozharness: 2017-11-22 20:39:57.686539Z] Finished install step (success)
12:39:57 INFO - [mozharness: 2017-11-22 20:39:57.686685Z] Running setup-mitmproxy step.
12:39:57 INFO - Running pre-action listener: _resource_record_pre_action
12:39:57 INFO - Running main action method: setup_mitmproxy
12:39:57 INFO - Skipping: mitmproxy is not required
12:39:57 INFO - Running post-action listener: _resource_record_post_action
12:39:57 INFO - [mozharness: 2017-11-22 20:39:57.687924Z] Finished setup-mitmproxy step (success)
12:39:57 INFO - [mozharness: 2017-11-22 20:39:57.688228Z] Running run-tests step.
12:39:57 INFO - Running pre-action listener: _resource_record_pre_action
12:39:57 INFO - Running pre-action listener: _set_gcov_prefix
12:39:57 INFO - Running main action method: run_tests
12:39:57 WARNING - Try message not found.
12:39:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
12:39:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
12:39:57 INFO - Python 2.7.3
12:39:57 INFO - Return code: 0
12:39:57 INFO - grabbing minidump binary from tooltool
12:39:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:39:57 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 0x2649620>, '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 0x264a520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264a9b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
12:39:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
12:39:57 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
12:39:57 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
12:39:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
12:39:57 INFO - Return code: 0
12:39:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
12:39:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
12:39:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
12:39:57 INFO - ENV: RUST_BACKTRACE is now full
12:39:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
12:39:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
12:39:57 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/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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
12:39:57 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/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-031 --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
12:39:57 INFO - Using env: {'DISPLAY': ':0',
12:39:57 INFO - 'HOME': '/home/cltbld',
12:39:57 INFO - 'LANG': 'en_US.UTF-8',
12:39:57 INFO - 'LANGUAGE': 'en_US:en',
12:39:57 INFO - 'LOGNAME': 'cltbld',
12:39:57 INFO - 'MAIL': '/var/mail/cltbld',
12:39:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:39:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:39:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:39:57 INFO - 'MOZ_NO_REMOTE': '1',
12:39:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:39:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:39:57 INFO - 'NO_EM_RESTART': '1',
12:39:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:39:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:39:57 INFO - 'PWD': '/builds/slave/test',
12:39:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:39:57 INFO - 'RUST_BACKTRACE': 'full',
12:39:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:39:57 INFO - 'SHELL': '/bin/bash',
12:39:57 INFO - 'SHLVL': '1',
12:39:57 INFO - 'TERM': 'linux',
12:39:57 INFO - 'TMOUT': '86400',
12:39:57 INFO - 'USER': 'cltbld',
12:39:57 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:39:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:39:57 INFO - '_': '/tools/buildbot/bin/python'}
12:39:57 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/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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
12:39:58 INFO - mozversion application_buildid: 20171122193700
12:39:58 INFO - mozversion application_changeset: 329fb4d184615224539b1bb578e64e39f68a4836
12:39:58 INFO - mozversion application_display_name: Nightly
12:39:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:39:58 INFO - mozversion application_name: Firefox
12:39:58 INFO - mozversion application_remotingname: firefox
12:39:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
12:39:58 INFO - mozversion application_vendor: Mozilla
12:39:58 INFO - mozversion application_version: 59.0a1
12:39:58 INFO - mozversion platform_buildid: 20171122193700
12:39:58 INFO - mozversion platform_changeset: 329fb4d184615224539b1bb578e64e39f68a4836
12:39:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
12:39:58 INFO - mozversion platform_version: 59.0a1
12:39:58 INFO - using testdate: 1511383198
12:39:58 INFO - actual date: 1511383198
12:39:58 INFO - starting webserver on 'localhost:39671'
12:39:58 INFO - SUITE-START | Running 2 tests
12:39:58 INFO - TEST-START | basic_compositor_video
12:39:58 INFO - Initialising browser for basic_compositor_video test...
12:39:58 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:39:58 INFO - Installing Add-ons
12:39:58 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39671/getInfo.html -profile /tmp/tmpMYHtmv/profile
12:39:58 INFO - TEST-INFO | started process 13378 (/builds/slave/test/build/application/firefox/firefox http://localhost:39671/getInfo.html)
12:40:03 INFO - TEST-INFO | 13378: exit 0
12:40:03 INFO - Browser initialized.
12:40:03 INFO - Running cycle 1/1 for basic_compositor_video test...
12:40:03 INFO - Using env: {'DISPLAY': ':0',
12:40:03 INFO - 'HOME': '/home/cltbld',
12:40:03 INFO - 'JSGC_DISABLE_POISONING': '1',
12:40:03 INFO - 'LANG': 'en_US.UTF-8',
12:40:03 INFO - 'LANGUAGE': 'en_US:en',
12:40:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:40:03 INFO - 'LOGNAME': 'cltbld',
12:40:03 INFO - 'MAIL': '/var/mail/cltbld',
12:40:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:40:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:40:03 INFO - 'MOZ_CRASHREPORTER': '1',
12:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:40:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:40:03 INFO - 'MOZ_NO_REMOTE': '1',
12:40:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:40:03 INFO - 'NO_EM_RESTART': '1',
12:40:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:40:03 INFO - 'PWD': '/builds/slave/test',
12:40:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:40:03 INFO - 'RUST_BACKTRACE': 'full',
12:40:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:40:03 INFO - 'SHELL': '/bin/bash',
12:40:03 INFO - 'SHLVL': '1',
12:40:03 INFO - 'STYLO_FORCE_DISABLED': '1',
12:40:03 INFO - 'TERM': 'linux',
12:40:03 INFO - 'TMOUT': '86400',
12:40:03 INFO - 'USER': 'cltbld',
12:40:03 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:40:03 INFO - '_': '/tools/buildbot/bin/python'}
12:40:03 INFO - TEST-INFO | started process 13646 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMYHtmv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
12:40:04 INFO - PID 13646 |
12:40:04 INFO - PID 13646 | (/builds/slave/test/build/application/firefox/firefox:13698): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:40:04 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6893665540540541 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6718796992481202 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.749559766763849 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7305147058823525 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.006675567423231 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.066341463414637 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.4934575087310815 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.408483816013633 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5354981549815525 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.668711048158641 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.870953947368411 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758219512195128 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.280921052631587 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.753279569892475 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607542016806727 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907470238095206 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.152862453531581 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.640106382978743 ms/frame
12:40:50 INFO - PID 13646 |
12:40:50 INFO - PID 13646 | Cycle 1(1): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:41:37 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6945736871823829 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6711403508771934 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.738348783314021 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7097564102564102 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.121284340659341 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.202857142857142 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.4933003492433072 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4258647260274 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5570462962962965 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.573286908077998 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 10.000383333333339 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.524452380952374 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.540365384615395 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.758360215053765 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.662573839662443 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.7703823529412 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.676867704280134 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05267955801107 ms/frame
12:41:37 INFO - PID 13646 |
12:41:37 INFO - PID 13646 | Cycle 1(2): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:42:23 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680145576707727 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6729682274247495 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7641358024691358 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7655119152691967 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.088181198910082 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.168697916666668 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.632451573849882 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.547304964539001 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.671701323251421 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.716257142857139 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.496503164556964 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.394342723004707 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.451431297709915 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.70053475935829 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.449958506224077 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.837514792899414 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.676595330739278 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.994862637362651 ms/frame
12:42:23 INFO - PID 13646 |
12:42:23 INFO - PID 13646 | Cycle 1(3): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:43:09 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6793088975937327 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6712740183792818 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7794098457888494 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.766978798586572 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.066043360433604 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.115462962962963 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.514186182669789 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.443356282271945 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.598190298507465 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.465163934426229 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.903432343234329 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.665797101449273 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.238033707865183 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.698877005347567 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.452199170124464 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053102409638567 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583899613899598 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11275000000003 ms/frame
12:43:09 INFO - PID 13646 |
12:43:09 INFO - PID 13646 | Cycle 1(4): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:43:55 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.683073471676949 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6675583333333335 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.747393709959231 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7352211621856029 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.060250338294993 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.176388308977035 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.5421074380165276 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4798000000000036 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619447565543071 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634676056338022 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.870279605263166 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.804754901960775 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.282612781954885 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.872173913043495 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.448672199170144 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.906071428571392 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.366344696969712 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.99266483516484 ms/frame
12:43:55 INFO - PID 13646 |
12:43:55 INFO - PID 13646 | Cycle 1(5): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:44:41 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6855449438202248 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6715288220551376 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7346213872832368 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7258714408973252 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.18515341701534 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.008827655310623 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.4933119906868444 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.432101200686108 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.639840225563904 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.588030726256992 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.774706840390877 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.218986175115209 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.363996212121217 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.931065573770503 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.71591101694916 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90645833333331 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.23981273408242 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.931803278688507 ms/frame
12:44:41 INFO - PID 13646 |
12:44:41 INFO - PID 13646 | Cycle 1(6): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:45:27 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800951847704368 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670333333333338 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.717727532913566 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7333882149046793 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1505601659751035 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.194989517819707 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.6542935444579774 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5539431616341077 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.567504638218928 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5272790055248615 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.616426282051284 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.709417475728152 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.364772727272738 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.811027027027032 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.400000000000013 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.980419161676666 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.322301886792438 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.175083798882703 ms/frame
12:45:27 INFO - PID 13646 |
12:45:27 INFO - PID 13646 | Cycle 1(7): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:46:14 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6809075630252102 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6691659716430363 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7356275303643722 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7022170212765955 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.077676630434783 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8624227799227775 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.477022016222482 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3960696095076397 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.65064030131827 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.450994550408717 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.710275080906143 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.437264150943399 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.071900369003679 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.817027027027034 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.554581589958183 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.906934523809511 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.366685606060607 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.871576086956535 ms/frame
12:46:14 INFO - PID 13646 |
12:46:14 INFO - PID 13646 | Cycle 1(8): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:47:00 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686815626756605 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6700667779632723 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7464318975552968 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7159948542024024 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.161310679611651 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.133078512396694 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.5263631022326667 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.467876949740035 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693396584440224 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589762569832408 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.647266881028937 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.218456221198169 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.36530303030304 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.753897849462358 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296229508196717 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.839970414201193 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58345559845559 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11627777777778 ms/frame
12:47:00 INFO - PID 13646 |
12:47:00 INFO - PID 13646 | Cycle 1(9): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:47:46 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800027995520717 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6731312709030104 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.740739559164733 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7011522108843533 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.173956884561893 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.132520661157024 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.690307503075032 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5920377019748644 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.760403071017271 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.54198060941829 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.204156441717792 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.482274881516581 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.197500000000014 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.587566137566114 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.60533613445378 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.701491228070143 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.196175373134345 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.696122994652383 ms/frame
12:47:46 INFO - PID 13646 |
12:47:46 INFO - PID 13646 | Cycle 1(10): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:48:32 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6866722878021359 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6758040201005024 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7312608193883443 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.712320205479452 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 3.96885582010582 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1509128630705385 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.554668246445497 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5095263157894743 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.629043151969979 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.571587743732593 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.526507936507931 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.57107655502394 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.324849056603783 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.871902173913044 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.29620901639345 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.697076023391796 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.23846441947565 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.874320652173912 ms/frame
12:48:32 INFO - PID 13646 |
12:48:32 INFO - PID 13646 | Cycle 1(11): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:49:18 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6794963626189146 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6740292887029287 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7488170163170165 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7365190972222218 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_startup = 4.116268861454047 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.937539370078741 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_startup = 3.589479665071771 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1_inclip = 3.523283450704223 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.454818181818178 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.543157894736847 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_startup = 9.434795597484275 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.480p.60fps.webm_scale_2_inclip = 9.095727272727279 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.322150943396236 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.756102150537606 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.554560669456071 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.910714285714286 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583841698841685 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.989697802197803 ms/frame
12:49:18 INFO - PID 13646 |
12:49:18 INFO - PID 13646 | Cycle 1(12): loaded http://localhost:39671/tests/video/video_playback.html (next: http://localhost:39671/tests/video/video_playback.html)
12:49:19 INFO - PID 13646 | __start_tp_report
12:49:19 INFO - PID 13646 | _x_x_mozilla_page_load
12:49:19 INFO - PID 13646 | _x_x_mozilla_page_load_details
12:49:19 INFO - PID 13646 | |i|pagename|runs|
12:49:19 INFO - PID 13646 | |0;240p.120fps.mp4_scale_1_startup;1.6893665540540541;1.6945736871823829;1.680145576707727;1.6793088975937327;1.683073471676949;1.6855449438202248;1.6800951847704368;1.6809075630252102;1.686815626756605;1.6800027995520717;1.6866722878021359;1.6794963626189146
12:49:19 INFO - PID 13646 | |1;240p.120fps.mp4_scale_1_inclip;1.6718796992481202;1.6711403508771934;1.6729682274247495;1.6712740183792818;1.6675583333333335;1.6715288220551376;1.6670333333333338;1.6691659716430363;1.6700667779632723;1.6731312709030104;1.6758040201005024;1.6740292887029287
12:49:19 INFO - PID 13646 | |2;240p.120fps.mp4_scale_1.1_startup;1.749559766763849;1.738348783314021;1.7641358024691358;1.7794098457888494;1.747393709959231;1.7346213872832368;1.717727532913566;1.7356275303643722;1.7464318975552968;1.740739559164733;1.7312608193883443;1.7488170163170165
12:49:19 INFO - PID 13646 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7305147058823525;1.7097564102564102;1.7655119152691967;1.766978798586572;1.7352211621856029;1.7258714408973252;1.7333882149046793;1.7022170212765955;1.7159948542024024;1.7011522108843533;1.712320205479452;1.7365190972222218
12:49:19 INFO - PID 13646 | |4;240p.120fps.mp4_scale_2_startup;4.006675567423231;4.121284340659341;4.088181198910082;4.066043360433604;4.060250338294993;4.18515341701534;4.1505601659751035;4.077676630434783;4.161310679611651;4.173956884561893;3.96885582010582;4.116268861454047
12:49:19 INFO - PID 13646 | |5;240p.120fps.mp4_scale_2_inclip;4.066341463414637;4.202857142857142;4.168697916666668;4.115462962962963;4.176388308977035;4.008827655310623;4.194989517819707;3.8624227799227775;4.133078512396694;4.132520661157024;4.1509128630705385;3.937539370078741
12:49:19 INFO - PID 13646 | |6;480p.60fps.webm_scale_1_startup;3.4934575087310815;3.4933003492433072;3.632451573849882;3.514186182669789;3.5421074380165276;3.4933119906868444;3.6542935444579774;3.477022016222482;3.5263631022326667;3.690307503075032;3.554668246445497;3.589479665071771
12:49:19 INFO - PID 13646 | |7;480p.60fps.webm_scale_1_inclip;3.408483816013633;3.4258647260274;3.547304964539001;3.443356282271945;3.4798000000000036;3.432101200686108;3.5539431616341077;3.3960696095076397;3.467876949740035;3.5920377019748644;3.5095263157894743;3.523283450704223
12:49:19 INFO - PID 13646 | |8;480p.60fps.webm_scale_1.1_startup;5.5354981549815525;5.5570462962962965;5.671701323251421;5.598190298507465;5.619447565543071;5.639840225563904;5.567504638218928;5.65064030131827;5.693396584440224;5.760403071017271;5.629043151969979;5.454818181818178
12:49:19 INFO - PID 13646 | |9;480p.60fps.webm_scale_1.1_inclip;5.668711048158641;5.573286908077998;5.716257142857139;5.465163934426229;5.634676056338022;5.588030726256992;5.5272790055248615;5.450994550408717;5.589762569832408;5.54198060941829;5.571587743732593;5.543157894736847
12:49:19 INFO - PID 13646 | |10;480p.60fps.webm_scale_2_startup;9.870953947368411;10.000383333333339;9.496503164556964;9.903432343234329;9.870279605263166;9.774706840390877;9.616426282051284;9.710275080906143;9.647266881028937;9.204156441717792;9.526507936507931;9.434795597484275
12:49:19 INFO - PID 13646 | |11;480p.60fps.webm_scale_2_inclip;9.758219512195128;9.524452380952374;9.394342723004707;9.665797101449273;9.804754901960775;9.218986175115209;9.709417475728152;9.437264150943399;9.218456221198169;9.482274881516581;9.57107655502394;9.095727272727279
12:49:19 INFO - PID 13646 | |12;1080p.60fps.mp4_scale_1_startup;11.280921052631587;11.540365384615395;11.451431297709915;11.238033707865183;11.282612781954885;11.363996212121217;11.364772727272738;11.071900369003679;11.36530303030304;11.197500000000014;11.324849056603783;11.322150943396236
12:49:19 INFO - PID 13646 | |13;1080p.60fps.mp4_scale_1_inclip;10.753279569892475;10.758360215053765;10.70053475935829;10.698877005347567;10.872173913043495;10.931065573770503;10.811027027027032;10.817027027027034;10.753897849462358;10.587566137566114;10.871902173913044;10.756102150537606
12:49:19 INFO - PID 13646 | |14;1080p.60fps.mp4_scale_1.1_startup;12.607542016806727;12.662573839662443;12.449958506224077;12.452199170124464;12.448672199170144;12.71591101694916;12.400000000000013;12.554581589958183;12.296229508196717;12.60533613445378;12.29620901639345;12.554560669456071
12:49:19 INFO - PID 13646 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.907470238095206;11.7703823529412;11.837514792899414;12.053102409638567;11.906071428571392;11.90645833333331;11.980419161676666;11.906934523809511;11.839970414201193;11.701491228070143;11.697076023391796;11.910714285714286
12:49:19 INFO - PID 13646 | |16;1080p.60fps.mp4_scale_2_startup;11.152862453531581;11.676867704280134;11.676595330739278;11.583899613899598;11.366344696969712;11.23981273408242;11.322301886792438;11.366685606060607;11.58345559845559;11.196175373134345;11.23846441947565;11.583841698841685
12:49:19 INFO - PID 13646 | |17;1080p.60fps.mp4_scale_2_inclip;10.640106382978743;11.05267955801107;10.994862637362651;11.11275000000003;10.99266483516484;10.931803278688507;11.175083798882703;10.871576086956535;11.11627777777778;10.696122994652383;10.874320652173912;10.989697802197803
12:49:19 INFO - PID 13646 | __end_tp_report
12:49:19 INFO - PID 13646 | __start_cc_report
12:49:19 INFO - PID 13646 | _x_x_mozilla_cycle_collect,608
12:49:19 INFO - PID 13646 | __end_cc_report
12:49:19 INFO - PID 13646 | __startTimestamp1511383759134__endTimestamp
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | ------- Summary: start -------
12:49:19 INFO - PID 13646 | Number of tests: 18
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
12:49:19 INFO - PID 13646 | 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
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#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
12:49:19 INFO - PID 13646 | 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
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.75 stddev:0.02 (0.9%) stddev-sans-first:0.02
12:49:19 INFO - PID 13646 | Values: 1.7 1.7 1.8 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.73 Median:1.73 stddev:0.02 (1.3%) stddev-sans-first:0.02
12:49:19 INFO - PID 13646 | Values: 1.7 1.7 1.8 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.10 Median:4.12 stddev:0.07 (1.6%) stddev-sans-first:0.06
12:49:19 INFO - PID 13646 | Values: 4.0 4.1 4.1 4.1 4.1 4.2 4.2 4.1 4.2 4.2 4.0 4.1
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.10 Median:4.14 stddev:0.11 (2.6%) stddev-sans-first:0.11
12:49:19 INFO - PID 13646 | Values: 4.1 4.2 4.2 4.1 4.2 4.0 4.2 3.9 4.1 4.1 4.2 3.9
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.56 Median:3.55 stddev:0.07 (2.0%) stddev-sans-first:0.07
12:49:19 INFO - PID 13646 | Values: 3.5 3.5 3.6 3.5 3.5 3.5 3.7 3.5 3.5 3.7 3.6 3.6
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.48 Median:3.49 stddev:0.06 (1.8%) stddev-sans-first:0.06
12:49:19 INFO - PID 13646 | Values: 3.4 3.4 3.5 3.4 3.5 3.4 3.6 3.4 3.5 3.6 3.5 3.5
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.61 Median:5.63 stddev:0.08 (1.4%) stddev-sans-first:0.08
12:49:19 INFO - PID 13646 | Values: 5.5 5.6 5.7 5.6 5.6 5.6 5.6 5.7 5.7 5.8 5.6 5.5
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.57 Median:5.58 stddev:0.08 (1.4%) stddev-sans-first:0.07
12:49:19 INFO - PID 13646 | Values: 5.7 5.6 5.7 5.5 5.6 5.6 5.5 5.5 5.6 5.5 5.6 5.5
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.67 Median:9.68 stddev:0.23 (2.4%) stddev-sans-first:0.23
12:49:19 INFO - PID 13646 | Values: 9.9 10.0 9.5 9.9 9.9 9.8 9.6 9.7 9.6 9.2 9.5 9.4
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.49 Median:9.55 stddev:0.23 (2.4%) stddev-sans-first:0.22
12:49:19 INFO - PID 13646 | Values: 9.8 9.5 9.4 9.7 9.8 9.2 9.7 9.4 9.2 9.5 9.6 9.1
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.32 Median:11.34 stddev:0.12 (1.1%) stddev-sans-first:0.13
12:49:19 INFO - PID 13646 | Values: 11.3 11.5 11.5 11.2 11.3 11.4 11.4 11.1 11.4 11.2 11.3 11.3
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.78 Median:10.78 stddev:0.09 (0.9%) stddev-sans-first:0.10
12:49:19 INFO - PID 13646 | Values: 10.8 10.8 10.7 10.7 10.9 10.9 10.8 10.8 10.8 10.6 10.9 10.8
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.50 Median:12.55 stddev:0.14 (1.1%) stddev-sans-first:0.14
12:49:19 INFO - PID 13646 | Values: 12.6 12.7 12.4 12.5 12.4 12.7 12.4 12.6 12.3 12.6 12.3 12.6
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.87 Median:11.91 stddev:0.11 (0.9%) stddev-sans-first:0.11
12:49:19 INFO - PID 13646 | Values: 11.9 11.8 11.8 12.1 11.9 11.9 12.0 11.9 11.8 11.7 11.7 11.9
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.42 Median:11.48 stddev:0.19 (1.7%) stddev-sans-first:0.18
12:49:19 INFO - PID 13646 | Values: 11.2 11.7 11.7 11.6 11.4 11.2 11.3 11.4 11.6 11.2 11.2 11.6
12:49:19 INFO - PID 13646 |
12:49:19 INFO - PID 13646 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.95 Median:10.99 stddev:0.16 (1.5%) stddev-sans-first:0.14
12:49:19 INFO - PID 13646 | Values: 10.6 11.1 11.0 11.1 11.0 10.9 11.2 10.9 11.1 10.7 10.9 11.0
12:49:19 INFO - PID 13646 | -------- Summary: end --------
12:49:19 INFO - PID 13646 |
12:49:19 INFO - TEST-INFO | 13646: exit 0
12:49:19 INFO - TEST-OK | basic_compositor_video | took 561550ms
12:49:19 INFO - TEST-START | glvideo
12:49:19 INFO - Initialising browser for glvideo test...
12:49:19 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
12:49:19 INFO - Installing Add-ons
12:49:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:39671/getInfo.html -profile /tmp/tmphGJoKu/profile
12:49:19 INFO - TEST-INFO | started process 14884 (/builds/slave/test/build/application/firefox/firefox http://localhost:39671/getInfo.html)
12:49:25 INFO - TEST-INFO | 14884: exit 0
12:49:25 INFO - Browser initialized.
12:49:25 INFO - Running cycle 1/1 for glvideo test...
12:49:25 INFO - Using env: {'DISPLAY': ':0',
12:49:25 INFO - 'HOME': '/home/cltbld',
12:49:25 INFO - 'JSGC_DISABLE_POISONING': '1',
12:49:25 INFO - 'LANG': 'en_US.UTF-8',
12:49:25 INFO - 'LANGUAGE': 'en_US:en',
12:49:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:49:25 INFO - 'LOGNAME': 'cltbld',
12:49:25 INFO - 'MAIL': '/var/mail/cltbld',
12:49:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:49:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:49:25 INFO - 'MOZ_CRASHREPORTER': '1',
12:49:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:49:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:49:25 INFO - 'MOZ_NO_REMOTE': '1',
12:49:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:49:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:49:25 INFO - 'NO_EM_RESTART': '1',
12:49:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:49:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:49:25 INFO - 'PWD': '/builds/slave/test',
12:49:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:49:25 INFO - 'RUST_BACKTRACE': 'full',
12:49:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:49:25 INFO - 'SHELL': '/bin/bash',
12:49:25 INFO - 'SHLVL': '1',
12:49:25 INFO - 'STYLO_FORCE_DISABLED': '1',
12:49:25 INFO - 'TERM': 'linux',
12:49:25 INFO - 'TMOUT': '86400',
12:49:25 INFO - 'USER': 'cltbld',
12:49:25 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954',
12:49:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:49:25 INFO - '_': '/tools/buildbot/bin/python'}
12:49:25 INFO - TEST-INFO | started process 15154 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphGJoKu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:49:26 INFO - PID 15154 |
12:49:26 INFO - PID 15154 | (/builds/slave/test/build/application/firefox/firefox:15205): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:49:26 INFO - PID 15154 |
12:49:27 INFO - PID 15154 | [talos glvideo result] Mean tick time across 100 ticks: 6.755750000000001 ms
12:49:27 INFO - PID 15154 | Cycle 1(1): loaded http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html)
12:49:29 INFO - PID 15154 | [talos glvideo result] Mean tick time across 100 ticks: 6.558050000000001 ms
12:49:29 INFO - PID 15154 | Cycle 1(2): loaded http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html)
12:49:30 INFO - PID 15154 | [talos glvideo result] Mean tick time across 100 ticks: 6.56915 ms
12:49:30 INFO - PID 15154 | Cycle 1(3): loaded http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html)
12:49:31 INFO - PID 15154 | [talos glvideo result] Mean tick time across 100 ticks: 6.518399999999999 ms
12:49:31 INFO - PID 15154 | Cycle 1(4): loaded http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html)
12:49:33 INFO - PID 15154 | [talos glvideo result] Mean tick time across 100 ticks: 6.715599999999999 ms
12:49:33 INFO - PID 15154 | Cycle 1(5): loaded http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:39671/tests/webgl/benchmarks/video/video_upload.html)
12:49:33 INFO - PID 15154 | __start_tp_report
12:49:33 INFO - PID 15154 | _x_x_mozilla_page_load
12:49:33 INFO - PID 15154 | _x_x_mozilla_page_load_details
12:49:33 INFO - PID 15154 | |i|pagename|runs|
12:49:33 INFO - PID 15154 | |0;Mean tick time across 100 ticks: ;6.755750000000001;6.558050000000001;6.56915;6.518399999999999;6.715599999999999
12:49:33 INFO - PID 15154 | __end_tp_report
12:49:33 INFO - PID 15154 | __start_cc_report
12:49:33 INFO - PID 15154 | _x_x_mozilla_cycle_collect,294
12:49:33 INFO - PID 15154 | __end_cc_report
12:49:33 INFO - PID 15154 | __startTimestamp1511383773373__endTimestamp
12:49:33 INFO - PID 15154 |
12:49:33 INFO - PID 15154 | ------- Summary: start -------
12:49:33 INFO - PID 15154 | Number of tests: 1
12:49:33 INFO - PID 15154 |
12:49:33 INFO - PID 15154 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.62 Median:6.57 stddev:0.11 (1.6%) stddev-sans-first:0.09
12:49:33 INFO - PID 15154 | Values: 6.8 6.6 6.6 6.5 6.7
12:49:33 INFO - PID 15154 | -------- Summary: end --------
12:49:33 INFO - PID 15154 |
12:49:33 INFO - PID 15154 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:49:33 INFO - TEST-INFO | 15154: exit 0
12:49:33 INFO - TEST-OK | glvideo | took 14300ms
12:49:33 INFO - SUITE-END | took 575s
12:49:34 INFO - Completed test suite (00:09:36)
12:49:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.56894418539677, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6893665540540541, 1.6945736871823829, 1.680145576707727, 1.6793088975937327, 1.683073471676949, 1.6855449438202248, 1.6800951847704368, 1.6809075630252102, 1.686815626756605, 1.6800027995520717, 1.6866722878021359, 1.6794963626189146], "value": 1.6809075630252102, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6718796992481202, 1.6711403508771934, 1.6729682274247495, 1.6712740183792818, 1.6675583333333335, 1.6715288220551376, 1.6670333333333338, 1.6691659716430363, 1.6700667779632723, 1.6731312709030104, 1.6758040201005024, 1.6740292887029287], "value": 1.6712740183792818, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.749559766763849, 1.738348783314021, 1.7641358024691358, 1.7794098457888494, 1.747393709959231, 1.7346213872832368, 1.717727532913566, 1.7356275303643722, 1.7464318975552968, 1.740739559164733, 1.7312608193883443, 1.7488170163170165], "value": 1.740739559164733, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7305147058823525, 1.7097564102564102, 1.7655119152691967, 1.766978798586572, 1.7352211621856029, 1.7258714408973252, 1.7333882149046793, 1.7022170212765955, 1.7159948542024024, 1.7011522108843533, 1.712320205479452, 1.7365190972222218], "value": 1.7258714408973252, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.006675567423231, 4.121284340659341, 4.088181198910082, 4.066043360433604, 4.060250338294993, 4.18515341701534, 4.1505601659751035, 4.077676630434783, 4.161310679611651, 4.173956884561893, 3.96885582010582, 4.116268861454047], "value": 4.116268861454047, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.066341463414637, 4.202857142857142, 4.168697916666668, 4.115462962962963, 4.176388308977035, 4.008827655310623, 4.194989517819707, 3.8624227799227775, 4.133078512396694, 4.132520661157024, 4.1509128630705385, 3.937539370078741], "value": 4.133078512396694, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4934575087310815, 3.4933003492433072, 3.632451573849882, 3.514186182669789, 3.5421074380165276, 3.4933119906868444, 3.6542935444579774, 3.477022016222482, 3.5263631022326667, 3.690307503075032, 3.554668246445497, 3.589479665071771], "value": 3.5421074380165276, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.408483816013633, 3.4258647260274, 3.547304964539001, 3.443356282271945, 3.4798000000000036, 3.432101200686108, 3.5539431616341077, 3.3960696095076397, 3.467876949740035, 3.5920377019748644, 3.5095263157894743, 3.523283450704223], "value": 3.4798000000000036, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.5354981549815525, 5.5570462962962965, 5.671701323251421, 5.598190298507465, 5.619447565543071, 5.639840225563904, 5.567504638218928, 5.65064030131827, 5.693396584440224, 5.760403071017271, 5.629043151969979, 5.454818181818178], "value": 5.629043151969979, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.668711048158641, 5.573286908077998, 5.716257142857139, 5.465163934426229, 5.634676056338022, 5.588030726256992, 5.5272790055248615, 5.450994550408717, 5.589762569832408, 5.54198060941829, 5.571587743732593, 5.543157894736847], "value": 5.571587743732593, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.870953947368411, 10.000383333333339, 9.496503164556964, 9.903432343234329, 9.870279605263166, 9.774706840390877, 9.616426282051284, 9.710275080906143, 9.647266881028937, 9.204156441717792, 9.526507936507931, 9.434795597484275], "value": 9.647266881028937, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.758219512195128, 9.524452380952374, 9.394342723004707, 9.665797101449273, 9.804754901960775, 9.218986175115209, 9.709417475728152, 9.437264150943399, 9.218456221198169, 9.482274881516581, 9.57107655502394, 9.095727272727279], "value": 9.482274881516581, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.280921052631587, 11.540365384615395, 11.451431297709915, 11.238033707865183, 11.282612781954885, 11.363996212121217, 11.364772727272738, 11.071900369003679, 11.36530303030304, 11.197500000000014, 11.324849056603783, 11.322150943396236], "value": 11.324849056603783, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.753279569892475, 10.758360215053765, 10.70053475935829, 10.698877005347567, 10.872173913043495, 10.931065573770503, 10.811027027027032, 10.817027027027034, 10.753897849462358, 10.587566137566114, 10.871902173913044, 10.756102150537606], "value": 10.758360215053765, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.607542016806727, 12.662573839662443, 12.449958506224077, 12.452199170124464, 12.448672199170144, 12.71591101694916, 12.400000000000013, 12.554581589958183, 12.296229508196717, 12.60533613445378, 12.29620901639345, 12.554560669456071], "value": 12.452199170124464, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.907470238095206, 11.7703823529412, 11.837514792899414, 12.053102409638567, 11.906071428571392, 11.90645833333331, 11.980419161676666, 11.906934523809511, 11.839970414201193, 11.701491228070143, 11.697076023391796, 11.910714285714286], "value": 11.906071428571392, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.152862453531581, 11.676867704280134, 11.676595330739278, 11.583899613899598, 11.366344696969712, 11.23981273408242, 11.322301886792438, 11.366685606060607, 11.58345559845559, 11.196175373134345, 11.23846441947565, 11.583841698841685], "value": 11.366685606060607, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.640106382978743, 11.05267955801107, 10.994862637362651, 11.11275000000003, 10.99266483516484, 10.931803278688507, 11.175083798882703, 10.871576086956535, 11.11627777777778, 10.696122994652383, 10.874320652173912, 10.989697802197803], "value": 10.99266483516484, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.755750000000001, 6.558050000000001, 6.56915, 6.518399999999999, 6.715599999999999], "value": 6.5636, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
12:49:34 INFO - Return code: 0
12:49:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
12:49:34 INFO - # TBPL SUCCESS #
12:49:34 INFO - Running post-action listener: _package_coverage_data
12:49:34 INFO - Running post-action listener: _resource_record_post_action
12:49:34 INFO - [mozharness: 2017-11-22 20:49:34.515290Z] Finished run-tests step (success)
12:49:34 INFO - Running post-run listener: _resource_record_post_run
12:49:34 INFO - Total resource usage - Wall time: 596s; CPU: 39.0%; Read bytes: 151552; Write bytes: 315486208; Read time: 248; Write time: 253728
12:49:34 INFO - TinderboxPrint: CPU usage
38.6%
12:49:34 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 248
12:49:34 INFO - TinderboxPrint: I/O write bytes / time
315,486,208 / 253,728
12:49:34 INFO - TinderboxPrint: CPU idle
2,867.0 (61.4%)
12:49:34 INFO - TinderboxPrint: CPU system
299.0 (6.4%)
12:49:34 INFO - TinderboxPrint: CPU user
1,492.3 (31.9%)
12:49:34 INFO - TinderboxPrint: Swap in / out
0 / 0
12:49:34 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 6557696; Read time: 0; Write time: 1052
12:49:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
12:49:34 INFO - run-tests - Wall time: 577s; CPU: 40.0%; Read bytes: 147456; Write bytes: 307867648; Read time: 232; Write time: 252476
12:49:34 INFO - Running post-run listener: _upload_blobber_files
12:49:34 INFO - Blob upload gear active.
12:49:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
12:49:34 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
12:49:34 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']
12:49:34 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
12:49:35 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:35 INFO - SNIMissingWarning
12:49:35 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:35 INFO - InsecurePlatformWarning
12:49:35 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:49:35 INFO - SubjectAltNameWarning
12:49:35 INFO - (blobuploader) - INFO - Open directory for files ...
12:49:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
12:49:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:49:35 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:49:35 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:35 INFO - InsecurePlatformWarning
12:49:35 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:49:35 INFO - SubjectAltNameWarning
12:49:37 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:37 INFO - InsecurePlatformWarning
12:49:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:49:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:49:37 INFO - (blobuploader) - INFO - Done attempting.
12:49:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
12:49:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:49:37 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:49:37 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:37 INFO - InsecurePlatformWarning
12:49:38 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:49:38 INFO - SubjectAltNameWarning
12:49:38 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:38 INFO - InsecurePlatformWarning
12:49:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
12:49:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:49:38 INFO - (blobuploader) - INFO - Done attempting.
12:49:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
12:49:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:49:38 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:49:38 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:38 INFO - InsecurePlatformWarning
12:49:38 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:49:38 INFO - SubjectAltNameWarning
12:49:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:39 INFO - InsecurePlatformWarning
12:49:39 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
12:49:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:49:39 INFO - (blobuploader) - INFO - Done attempting.
12:49:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
12:49:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:49:39 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:49:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:39 INFO - InsecurePlatformWarning
12:49:39 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:49:39 INFO - SubjectAltNameWarning
12:49:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:49:40 INFO - InsecurePlatformWarning
12:49:40 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
12:49:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:49:40 INFO - (blobuploader) - INFO - Done attempting.
12:49:40 INFO - (blobuploader) - INFO - Iteration through files over.
12:49:40 INFO - Return code: 0
12:49:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
12:49:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
12:49:40 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca0e7f3f2e3b69f64b80a5c58a28a239e9800793e4df341544bd6f46229ca60ec3f5125614b30e908600bc8b602dab57db3b81c4db1784356fa0fd376cbd6ea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/423c91790fbaf1957e5a833e069a9efa5c0c6fbd8eb1afc80423be664cde775e42895aed1a605bbfc2f9d23a51637fd01f01bd592a829180912f56cfc61616a8", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61a420bd8870eebd5faaf83b44a25d0e018c0a4e9c2488925a0c9271b4ee3a4b87839696db0beae65d68e5ab29435e0eb87e059d2db4b7fb4de998a219271c31", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76805ec047719ebd998d90a6daf0113d474e9152d0c5a132e6c0b315f7504efefcdb664b6f927dfd539892fc239a862a9fdc4330100a514776f2979f18b109b6"}
12:49:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
12:49:40 INFO - Writing to file /builds/slave/test/properties/blobber_files
12:49:40 INFO - Contents:
12:49:40 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca0e7f3f2e3b69f64b80a5c58a28a239e9800793e4df341544bd6f46229ca60ec3f5125614b30e908600bc8b602dab57db3b81c4db1784356fa0fd376cbd6ea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/423c91790fbaf1957e5a833e069a9efa5c0c6fbd8eb1afc80423be664cde775e42895aed1a605bbfc2f9d23a51637fd01f01bd592a829180912f56cfc61616a8", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61a420bd8870eebd5faaf83b44a25d0e018c0a4e9c2488925a0c9271b4ee3a4b87839696db0beae65d68e5ab29435e0eb87e059d2db4b7fb4de998a219271c31", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76805ec047719ebd998d90a6daf0113d474e9152d0c5a132e6c0b315f7504efefcdb664b6f927dfd539892fc239a862a9fdc4330100a514776f2979f18b109b6"}
12:49:40 INFO - Running post-run listener: copy_logs_to_upload_dir
12:49:40 INFO - Copying logs to upload dir...
12:49:40 INFO - mkdir: /builds/slave/test/build/upload/logs
12:49:40 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=656.680734
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 56 secs) (at 2017-11-22 12:49:40.643231) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-22 12:49:40.646927) =========
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=db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954
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/ca0e7f3f2e3b69f64b80a5c58a28a239e9800793e4df341544bd6f46229ca60ec3f5125614b30e908600bc8b602dab57db3b81c4db1784356fa0fd376cbd6ea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/423c91790fbaf1957e5a833e069a9efa5c0c6fbd8eb1afc80423be664cde775e42895aed1a605bbfc2f9d23a51637fd01f01bd592a829180912f56cfc61616a8", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61a420bd8870eebd5faaf83b44a25d0e018c0a4e9c2488925a0c9271b4ee3a4b87839696db0beae65d68e5ab29435e0eb87e059d2db4b7fb4de998a219271c31", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76805ec047719ebd998d90a6daf0113d474e9152d0c5a132e6c0b315f7504efefcdb664b6f927dfd539892fc239a862a9fdc4330100a514776f2979f18b109b6"}
build_url:https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011451
build_url: 'https://queue.taskcluster.net/v1/task/CDTDanMpR8mtMOODEcCVyQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ca0e7f3f2e3b69f64b80a5c58a28a239e9800793e4df341544bd6f46229ca60ec3f5125614b30e908600bc8b602dab57db3b81c4db1784356fa0fd376cbd6ea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/423c91790fbaf1957e5a833e069a9efa5c0c6fbd8eb1afc80423be664cde775e42895aed1a605bbfc2f9d23a51637fd01f01bd592a829180912f56cfc61616a8", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/61a420bd8870eebd5faaf83b44a25d0e018c0a4e9c2488925a0c9271b4ee3a4b87839696db0beae65d68e5ab29435e0eb87e059d2db4b7fb4de998a219271c31", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/76805ec047719ebd998d90a6daf0113d474e9152d0c5a132e6c0b315f7504efefcdb664b6f927dfd539892fc239a862a9fdc4330100a514776f2979f18b109b6"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-22 12:49:40.676599) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 12:49:40.676930) =========
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=db0483c52d987ecb441a263b000001ad-1511383120.792169-1979074954
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004483
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 12:49:40.760577) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-22 12:49:40.760882) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-22 12:49:40.761219) =========
========= Total master_lag: 0.20 =========