builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s-pgo
slave: talos-linux64-ix-060
starttime: 1508701462.63
results: success (0)
revision: a521d789a9d917251e70156049b653578490b799
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:22.628293) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:22.628842) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:22.629141) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:22.939650) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:22.939975) =========
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=337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-22 12:44:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 102M=0s
2017-10-22 12:44:23 (102 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.192337
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:23.166972) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:23.167268) =========
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=337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.018318
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:23.230435) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-22 12:44:23.230849) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a521d789a9d917251e70156049b653578490b799 --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 a521d789a9d917251e70156049b653578490b799 --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=337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-22 12:44:23,231 truncating revision to first 12 chars
2017-10-22 12:44:23,231 Setting DEBUG logging.
2017-10-22 12:44:23,231 attempt 1/10
2017-10-22 12:44:23,231 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a521d789a9d9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-22 12:44:23,484 unpacking tar archive at: mozilla-central-a521d789a9d9/testing/mozharness/
program finished with exit code 0
elapsedTime=1.014498
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-10-22 12:44:24.267246) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:24.270659) =========
script_repo_revision: a521d789a9d917251e70156049b653578490b799
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:24.271142) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:24.271418) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-22 12:44:24.288486) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 59 secs) (at 2017-10-22 12:44:24.288745) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
12:44:24 INFO - MultiFileLogger online at 20171022 12:44:24 in /builds/slave/test
12:44:24 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
12:44:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
12:44:24 INFO - {'append_to_log': False,
12:44:24 INFO - 'base_work_dir': '/builds/slave/test',
12:44:24 INFO - 'blob_upload_branch': 'Firefox',
12:44:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
12:44:24 INFO - 'branch': 'Firefox',
12:44:24 INFO - 'buildbot_json_path': 'buildprops.json',
12:44:24 INFO - 'code_coverage': False,
12:44:24 INFO - 'config_files': ('talos/linux_config.py',),
12:44:24 INFO - 'default_actions': ('clobber',
12:44:24 INFO - 'read-buildbot-config',
12:44:24 INFO - 'download-and-extract',
12:44:24 INFO - 'populate-webroot',
12:44:24 INFO - 'create-virtualenv',
12:44:24 INFO - 'install',
12:44:24 INFO - 'setup-mitmproxy',
12:44:24 INFO - 'run-tests'),
12:44:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
12:44:24 INFO - 'disable_ccov_upload': False,
12:44:24 INFO - 'disable_stylo': False,
12:44:24 INFO - 'download_minidump_stackwalk': True,
12:44:24 INFO - 'download_symbols': 'ondemand',
12:44:24 INFO - 'enable_stylo': False,
12:44:24 INFO - 'enable_webrender': False,
12:44:24 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
12:44:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
12:44:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
12:44:24 INFO - 'gecko_profile': False,
12:44:24 INFO - 'gecko_profile_interval': 0,
12:44:24 INFO - 'installer_path': 'installer.exe',
12:44:24 INFO - 'log_level': 'info',
12:44:24 INFO - 'log_name': 'talos',
12:44:24 INFO - 'log_to_console': True,
12:44:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
12:44:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
12:44:24 INFO - 'opt_config_files': (),
12:44:24 INFO - 'pip_index': False,
12:44:24 INFO - 'suite': 'g4-stylo-disabled-e10s',
12:44:24 INFO - 'system_bits': '32',
12:44:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
12:44:24 INFO - 'title': 'talos-linux64-ix-060',
12:44:24 INFO - 'tooltool_cache': '/builds/tooltool_cache',
12:44:24 INFO - 'use_talos_json': True,
12:44:24 INFO - 'verify': 'False',
12:44:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
12:44:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
12:44:24 INFO - 'work_dir': 'build'}
12:44:24 INFO - [mozharness: 2017-10-22 19:44:24.478253Z] Running clobber step.
12:44:24 INFO - Running pre-action listener: _resource_record_pre_action
12:44:24 INFO - Running main action method: clobber
12:44:24 INFO - rmtree: /builds/slave/test/build
12:44:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
12:44:25 INFO - Running post-action listener: _resource_record_post_action
12:44:25 INFO - [mozharness: 2017-10-22 19:44:25.495623Z] Finished clobber step (success)
12:44:25 INFO - [mozharness: 2017-10-22 19:44:25.495718Z] Running read-buildbot-config step.
12:44:25 INFO - Running pre-action listener: _resource_record_pre_action
12:44:25 INFO - Running main action method: read_buildbot_config
12:44:25 INFO - Using buildbot properties:
12:44:25 INFO - {
12:44:25 INFO - "project": "",
12:44:25 INFO - "product": "firefox",
12:44:25 INFO - "who": "ffxbld@noreply.mozilla.org",
12:44:25 INFO - "installer_path": "public/build/target.tar.bz2",
12:44:25 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
12:44:25 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
12:44:25 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-stylo-disabled-e10s",
12:44:25 INFO - "stage_platform": "linux64-pgo",
12:44:25 INFO - "basedir": "/builds/slave/test",
12:44:25 INFO - "buildnumber": 127,
12:44:25 INFO - "platform": "ubuntu64_hw",
12:44:25 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
12:44:25 INFO - "slavebuilddir": "test",
12:44:25 INFO - "taskId": "G2mJl-ADR82wn_-HA6eSmA",
12:44:25 INFO - "branch": "mozilla-central",
12:44:25 INFO - "script_repo_revision": "production",
12:44:25 INFO - "revision": "a521d789a9d917251e70156049b653578490b799",
12:44:25 INFO - "slavename": "talos-linux64-ix-060",
12:44:25 INFO - "repo_path": "mozilla-central"
12:44:25 INFO - }
12:44:25 INFO - Finding installer, test and symbols from parent task.
12:44:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2mJl-ADR82wn_-HA6eSmA'}, attempt #1
12:44:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2mJl-ADR82wn_-HA6eSmA'}, attempt #1
12:44:26 INFO - Task dependencies: fRd2fq4HQY6YXQCub_Q35w
12:44:26 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w'}, attempt #1
12:44:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2
12:44:26 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.test_packages.json
12:44:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.crashreporter-symbols.zip
12:44:26 INFO - Running post-action listener: _resource_record_post_action
12:44:26 INFO - [mozharness: 2017-10-22 19:44:26.880471Z] Finished read-buildbot-config step (success)
12:44:26 INFO - [mozharness: 2017-10-22 19:44:26.880687Z] Running download-and-extract step.
12:44:26 INFO - Running pre-action listener: _resource_record_pre_action
12:44:26 INFO - Running main action method: download_and_extract
12:44:26 INFO - mkdir: /builds/slave/test/build/tests
12:44:26 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:44:26 INFO - trying https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.test_packages.json
12:44:26 INFO - Downloading https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
12:44:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
12:44:27 INFO - Downloaded 989 bytes.
12:44:27 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
12:44:27 INFO - Using the following test package requirements:
12:44:27 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
12:44:27 INFO - u'common': [u'target.common.tests.zip'],
12:44:27 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
12:44:27 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
12:44:27 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
12:44:27 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
12:44:27 INFO - u'mozbase': [u'target.common.tests.zip'],
12:44:27 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
12:44:27 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
12:44:27 INFO - u'web-platform': [u'target.common.tests.zip',
12:44:27 INFO - u'target.web-platform.tests.tar.gz'],
12:44:27 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
12:44:27 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
12:44:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.common.tests.zip
12:44:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.common.tests.zip'}, attempt #1
12:44:27 INFO - Fetch https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.common.tests.zip into memory
12:44:29 INFO - Content-Length response header: 56505532
12:44:29 INFO - Bytes received: 56505532
12:44:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.talos.tests.zip
12:44:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.talos.tests.zip'}, attempt #1
12:44:40 INFO - Fetch https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.talos.tests.zip into memory
12:44:40 INFO - Content-Length response header: 18737502
12:44:40 INFO - Bytes received: 18737502
12:44:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:41 INFO - trying https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2
12:44:41 INFO - Downloading https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
12:44:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
12:44:44 INFO - Downloaded 63436247 bytes.
12:44:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2
12:44:44 INFO - mkdir: /builds/slave/test/properties
12:44:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
12:44:44 INFO - Writing to file /builds/slave/test/properties/build_url
12:44:44 INFO - Contents:
12:44:44 INFO - build_url:https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2
12:44:44 INFO - Running post-action listener: _resource_record_post_action
12:44:44 INFO - Running post-action listener: find_tests_for_verification
12:44:44 INFO - Running post-action listener: set_extra_try_arguments
12:44:44 INFO - [mozharness: 2017-10-22 19:44:44.575183Z] Finished download-and-extract step (success)
12:44:44 INFO - [mozharness: 2017-10-22 19:44:44.575357Z] Running populate-webroot step.
12:44:44 INFO - Running pre-action listener: _resource_record_pre_action
12:44:44 INFO - Running main action method: populate_webroot
12:44:44 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
12:44:44 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['tresize',
12:44:44 INFO - 'tcanvasmark']},
12:44:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
12:44:44 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['dromaeo_css',
12:44:44 INFO - 'kraken']},
12:44:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:44:44 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
12:44:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'tests': ['damp', 'tps']},
12:44:44 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['damp', 'tps']},
12:44:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
12:44:44 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['dromaeo_dom']},
12:44:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
12:44:44 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['basic_compositor_video',
12:44:44 INFO - 'glvideo']},
12:44:44 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
12:44:44 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['ts_paint_webext',
12:44:44 INFO - 'tp5o_webext']},
12:44:44 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
12:44:44 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:44:44 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:44:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:44:44 INFO - 'talos_options': ['--mitmproxy',
12:44:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:44:44 INFO - '--firstNonBlankPaint'],
12:44:44 INFO - 'tests': ['tp6_google_heavy',
12:44:44 INFO - 'tp6_youtube_heavy',
12:44:44 INFO - 'tp6_amazon_heavy',
12:44:44 INFO - 'tp6_facebook_heavy']},
12:44:44 INFO - 'other-e10s': {'tests': ['a11yr',
12:44:44 INFO - 'ts_paint',
12:44:44 INFO - 'tpaint',
12:44:44 INFO - 'sessionrestore',
12:44:44 INFO - 'sessionrestore_many_windows',
12:44:44 INFO - 'sessionrestore_no_auto_restore',
12:44:44 INFO - 'tabpaint',
12:44:44 INFO - 'cpstartup']},
12:44:44 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['a11yr',
12:44:44 INFO - 'ts_paint',
12:44:44 INFO - 'tpaint',
12:44:44 INFO - 'sessionrestore',
12:44:44 INFO - 'sessionrestore_many_windows',
12:44:44 INFO - 'sessionrestore_no_auto_restore',
12:44:44 INFO - 'tabpaint',
12:44:44 INFO - 'cpstartup']},
12:44:44 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
12:44:44 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
12:44:44 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['perf_reftest_singletons']},
12:44:44 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['perf_reftest']},
12:44:44 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
12:44:44 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['speedometer']},
12:44:44 INFO - 'svgr-e10s': {'tests': ['tsvgx',
12:44:44 INFO - 'tsvgr_opacity',
12:44:44 INFO - 'tart',
12:44:44 INFO - 'tscrollx',
12:44:44 INFO - 'tsvg_static']},
12:44:44 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['tsvgx',
12:44:44 INFO - 'tsvgr_opacity',
12:44:44 INFO - 'tart',
12:44:44 INFO - 'tscrollx',
12:44:44 INFO - 'tsvg_static']},
12:44:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
12:44:44 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'talos_options': ['--disable-stylo'],
12:44:44 INFO - 'tests': ['tp5o']},
12:44:44 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:44:44 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:44:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:44:44 INFO - 'talos_options': ['--mitmproxy',
12:44:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:44:44 INFO - '--firstNonBlankPaint'],
12:44:44 INFO - 'tests': ['tp6_google',
12:44:44 INFO - 'tp6_youtube',
12:44:44 INFO - 'tp6_amazon',
12:44:44 INFO - 'tp6_facebook']},
12:44:44 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:44:44 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:44:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:44:44 INFO - 'talos_options': ['--disable-stylo',
12:44:44 INFO - '--mitmproxy',
12:44:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:44:44 INFO - '--firstNonBlankPaint'],
12:44:44 INFO - 'tests': ['tp6_google',
12:44:44 INFO - 'tp6_youtube',
12:44:44 INFO - 'tp6_amazon',
12:44:44 INFO - 'tp6_facebook']},
12:44:44 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
12:44:44 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
12:44:44 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
12:44:44 INFO - 'talos_options': ['--stylo-threads=1',
12:44:44 INFO - '--mitmproxy',
12:44:44 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
12:44:44 INFO - '--firstNonBlankPaint'],
12:44:44 INFO - 'tests': ['tp6_google',
12:44:44 INFO - 'tp6_youtube',
12:44:44 INFO - 'tp6_amazon',
12:44:44 INFO - 'tp6_facebook']},
12:44:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'talos_options': ['--xperf_path',
12:44:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:44:44 INFO - 'tests': ['tp5n']},
12:44:44 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
12:44:44 INFO - 'talos_options': ['--disable-stylo',
12:44:44 INFO - '--xperf_path',
12:44:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
12:44:44 INFO - 'tests': ['tp5n']}}}
12:44:44 INFO - Running post-action listener: _resource_record_post_action
12:44:44 INFO - [mozharness: 2017-10-22 19:44:44.599230Z] Finished populate-webroot step (success)
12:44:44 INFO - [mozharness: 2017-10-22 19:44:44.599318Z] Running create-virtualenv step.
12:44:44 INFO - Running pre-action listener: _resource_record_pre_action
12:44:44 INFO - Running main action method: create_virtualenv
12:44:44 INFO - Creating virtualenv /builds/slave/test/build/venv
12:44:44 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:44:44 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
12:44:44 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
12:44:46 INFO - Using real prefix '/usr'
12:44:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python
12:44:46 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
12:44:48 INFO - Installing setuptools, pip, wheel...done.
12:44:48 INFO - Return code: 0
12:44:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
12:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:44:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:44:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
12:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
12:44:48 INFO - Using env: {'DISPLAY': ':0',
12:44:48 INFO - 'HOME': '/home/cltbld',
12:44:48 INFO - 'LANG': 'en_US.UTF-8',
12:44:48 INFO - 'LANGUAGE': 'en_US:en',
12:44:48 INFO - 'LOGNAME': 'cltbld',
12:44:48 INFO - 'MAIL': '/var/mail/cltbld',
12:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:44:48 INFO - 'MOZ_NO_REMOTE': '1',
12:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:44:48 INFO - 'NO_EM_RESTART': '1',
12:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:44:48 INFO - 'PWD': '/builds/slave/test',
12:44:48 INFO - 'SHELL': '/bin/bash',
12:44:48 INFO - 'SHLVL': '1',
12:44:48 INFO - 'TERM': 'linux',
12:44:48 INFO - 'TMOUT': '86400',
12:44:48 INFO - 'USER': 'cltbld',
12:44:48 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:44:48 INFO - '_': '/tools/buildbot/bin/python'}
12:44:48 INFO - Ignoring indexes: https://pypi.python.org/simple
12:44:48 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
12:44:48 INFO - Return code: 0
12:44:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
12:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:44:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:44:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:44:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
12:44:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
12:44:48 INFO - Using env: {'DISPLAY': ':0',
12:44:48 INFO - 'HOME': '/home/cltbld',
12:44:48 INFO - 'LANG': 'en_US.UTF-8',
12:44:48 INFO - 'LANGUAGE': 'en_US:en',
12:44:48 INFO - 'LOGNAME': 'cltbld',
12:44:48 INFO - 'MAIL': '/var/mail/cltbld',
12:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:44:48 INFO - 'MOZ_NO_REMOTE': '1',
12:44:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:44:48 INFO - 'NO_EM_RESTART': '1',
12:44:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:44:48 INFO - 'PWD': '/builds/slave/test',
12:44:48 INFO - 'SHELL': '/bin/bash',
12:44:48 INFO - 'SHLVL': '1',
12:44:48 INFO - 'TERM': 'linux',
12:44:48 INFO - 'TMOUT': '86400',
12:44:48 INFO - 'USER': 'cltbld',
12:44:48 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:44:48 INFO - '_': '/tools/buildbot/bin/python'}
12:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple
12:44:49 INFO - Collecting psutil>=3.1.1
12:44:50 INFO - Installing collected packages: psutil
12:44:50 INFO - Successfully installed psutil-3.1.1
12:44:51 INFO - Return code: 0
12:44:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
12:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:44:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:44:51 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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:44:51 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:44:51 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:44:51 INFO - Using env: {'DISPLAY': ':0',
12:44:51 INFO - 'HOME': '/home/cltbld',
12:44:51 INFO - 'LANG': 'en_US.UTF-8',
12:44:51 INFO - 'LANGUAGE': 'en_US:en',
12:44:51 INFO - 'LOGNAME': 'cltbld',
12:44:51 INFO - 'MAIL': '/var/mail/cltbld',
12:44:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:44:51 INFO - 'MOZ_NO_REMOTE': '1',
12:44:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:44:51 INFO - 'NO_EM_RESTART': '1',
12:44:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:44:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:44:51 INFO - 'PWD': '/builds/slave/test',
12:44:51 INFO - 'SHELL': '/bin/bash',
12:44:51 INFO - 'SHLVL': '1',
12:44:51 INFO - 'TERM': 'linux',
12:44:51 INFO - 'TMOUT': '86400',
12:44:51 INFO - 'USER': 'cltbld',
12:44:51 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:44:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:44:51 INFO - '_': '/tools/buildbot/bin/python'}
12:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple
12:44:51 INFO - Collecting mozsystemmonitor==0.3
12:44:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
12:44:52 INFO - Installing collected packages: mozsystemmonitor
12:44:52 INFO - Successfully installed mozsystemmonitor-0.3
12:44:52 INFO - Return code: 0
12:44:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
12:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:44:52 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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:44:52 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:44:52 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:44:52 INFO - Using env: {'DISPLAY': ':0',
12:44:52 INFO - 'HOME': '/home/cltbld',
12:44:52 INFO - 'LANG': 'en_US.UTF-8',
12:44:52 INFO - 'LANGUAGE': 'en_US:en',
12:44:52 INFO - 'LOGNAME': 'cltbld',
12:44:52 INFO - 'MAIL': '/var/mail/cltbld',
12:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:44:52 INFO - 'MOZ_NO_REMOTE': '1',
12:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:44:52 INFO - 'NO_EM_RESTART': '1',
12:44:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:44:52 INFO - 'PWD': '/builds/slave/test',
12:44:52 INFO - 'SHELL': '/bin/bash',
12:44:52 INFO - 'SHLVL': '1',
12:44:52 INFO - 'TERM': 'linux',
12:44:52 INFO - 'TMOUT': '86400',
12:44:52 INFO - 'USER': 'cltbld',
12:44:52 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:44:52 INFO - '_': '/tools/buildbot/bin/python'}
12:44:52 INFO - Ignoring indexes: https://pypi.python.org/simple
12:44:52 INFO - Collecting jsonschema==2.5.1
12:44:54 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
12:44:54 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
12:44:55 INFO - Installing collected packages: functools32, jsonschema
12:44:55 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
12:44:55 INFO - Return code: 0
12:44:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
12:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:44:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:44:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
12:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
12:44:55 INFO - Using env: {'DISPLAY': ':0',
12:44:55 INFO - 'HOME': '/home/cltbld',
12:44:55 INFO - 'LANG': 'en_US.UTF-8',
12:44:55 INFO - 'LANGUAGE': 'en_US:en',
12:44:55 INFO - 'LOGNAME': 'cltbld',
12:44:55 INFO - 'MAIL': '/var/mail/cltbld',
12:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:44:55 INFO - 'MOZ_NO_REMOTE': '1',
12:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:44:55 INFO - 'NO_EM_RESTART': '1',
12:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:44:55 INFO - 'PWD': '/builds/slave/test',
12:44:55 INFO - 'SHELL': '/bin/bash',
12:44:55 INFO - 'SHLVL': '1',
12:44:55 INFO - 'TERM': 'linux',
12:44:55 INFO - 'TMOUT': '86400',
12:44:55 INFO - 'USER': 'cltbld',
12:44:55 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:44:55 INFO - '_': '/tools/buildbot/bin/python'}
12:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple
12:44:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
12:44:55 INFO - Return code: 0
12:44:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
12:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:44:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:44:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:44:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
12:44:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
12:44:55 INFO - Using env: {'DISPLAY': ':0',
12:44:55 INFO - 'HOME': '/home/cltbld',
12:44:55 INFO - 'LANG': 'en_US.UTF-8',
12:44:55 INFO - 'LANGUAGE': 'en_US:en',
12:44:55 INFO - 'LOGNAME': 'cltbld',
12:44:55 INFO - 'MAIL': '/var/mail/cltbld',
12:44:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:44:55 INFO - 'MOZ_NO_REMOTE': '1',
12:44:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:44:55 INFO - 'NO_EM_RESTART': '1',
12:44:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:44:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:44:55 INFO - 'PWD': '/builds/slave/test',
12:44:55 INFO - 'SHELL': '/bin/bash',
12:44:55 INFO - 'SHLVL': '1',
12:44:55 INFO - 'TERM': 'linux',
12:44:55 INFO - 'TMOUT': '86400',
12:44:55 INFO - 'USER': 'cltbld',
12:44:55 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:44:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:44:55 INFO - '_': '/tools/buildbot/bin/python'}
12:44:55 INFO - Ignoring indexes: https://pypi.python.org/simple
12:44:56 INFO - Collecting blobuploader==1.2.4
12:44:57 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
12:44:58 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
12:44:59 INFO - Installing collected packages: requests, docopt, blobuploader
12:44:59 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
12:44:59 INFO - Return code: 0
12:44:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:44:59 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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:44:59 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:44:59 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:44:59 INFO - Using env: {'DISPLAY': ':0',
12:44:59 INFO - 'HOME': '/home/cltbld',
12:44:59 INFO - 'LANG': 'en_US.UTF-8',
12:44:59 INFO - 'LANGUAGE': 'en_US:en',
12:44:59 INFO - 'LOGNAME': 'cltbld',
12:44:59 INFO - 'MAIL': '/var/mail/cltbld',
12:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:44:59 INFO - 'MOZ_NO_REMOTE': '1',
12:44:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:44:59 INFO - 'NO_EM_RESTART': '1',
12:44:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:44:59 INFO - 'PWD': '/builds/slave/test',
12:44:59 INFO - 'SHELL': '/bin/bash',
12:44:59 INFO - 'SHLVL': '1',
12:44:59 INFO - 'TERM': 'linux',
12:44:59 INFO - 'TMOUT': '86400',
12:44:59 INFO - 'USER': 'cltbld',
12:44:59 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:44:59 INFO - '_': '/tools/buildbot/bin/python'}
12:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple
12:44:59 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:45:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:45:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:45:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:45:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:45:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:45:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:45:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:45:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:45:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:45:01 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:45:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:45:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:45:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:45:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:45:02 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:45:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:45:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
12:45:03 INFO - Running setup.py install for manifestparser: started
12:45:03 INFO - Running setup.py install for manifestparser: finished with status 'done'
12:45:03 INFO - Running setup.py install for mozcrash: started
12:45:03 INFO - Running setup.py install for mozcrash: finished with status 'done'
12:45:03 INFO - Running setup.py install for mozdebug: started
12:45:03 INFO - Running setup.py install for mozdebug: finished with status 'done'
12:45:03 INFO - Running setup.py install for mozdevice: started
12:45:03 INFO - Running setup.py install for mozdevice: finished with status 'done'
12:45:03 INFO - Running setup.py install for mozfile: started
12:45:04 INFO - Running setup.py install for mozfile: finished with status 'done'
12:45:04 INFO - Running setup.py install for mozhttpd: started
12:45:04 INFO - Running setup.py install for mozhttpd: finished with status 'done'
12:45:04 INFO - Running setup.py install for mozinfo: started
12:45:04 INFO - Running setup.py install for mozinfo: finished with status 'done'
12:45:04 INFO - Running setup.py install for mozInstall: started
12:45:04 INFO - Running setup.py install for mozInstall: finished with status 'done'
12:45:04 INFO - Running setup.py install for mozleak: started
12:45:05 INFO - Running setup.py install for mozleak: finished with status 'done'
12:45:05 INFO - Running setup.py install for mozlog: started
12:45:05 INFO - Running setup.py install for mozlog: finished with status 'done'
12:45:05 INFO - Running setup.py install for moznetwork: started
12:45:05 INFO - Running setup.py install for moznetwork: finished with status 'done'
12:45:05 INFO - Running setup.py install for mozprocess: started
12:45:05 INFO - Running setup.py install for mozprocess: finished with status 'done'
12:45:05 INFO - Running setup.py install for mozprofile: started
12:45:05 INFO - Running setup.py install for mozprofile: finished with status 'done'
12:45:05 INFO - Running setup.py install for mozrunner: started
12:45:06 INFO - Running setup.py install for mozrunner: finished with status 'done'
12:45:06 INFO - Running setup.py install for mozscreenshot: started
12:45:06 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
12:45:06 INFO - Running setup.py install for moztest: started
12:45:06 INFO - Running setup.py install for moztest: finished with status 'done'
12:45:06 INFO - Running setup.py install for mozversion: started
12:45:06 INFO - Running setup.py install for mozversion: finished with status 'done'
12:45:07 INFO - Successfully installed manifestparser-1.1 mozInstall-1.13 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.28 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
12:45:07 INFO - Return code: 0
12:45:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:45: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:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:45: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:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:45:07 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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:45:07 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:45:07 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:45:07 INFO - Using env: {'DISPLAY': ':0',
12:45:07 INFO - 'HOME': '/home/cltbld',
12:45:07 INFO - 'LANG': 'en_US.UTF-8',
12:45:07 INFO - 'LANGUAGE': 'en_US:en',
12:45:07 INFO - 'LOGNAME': 'cltbld',
12:45:07 INFO - 'MAIL': '/var/mail/cltbld',
12:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:45:07 INFO - 'MOZ_NO_REMOTE': '1',
12:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:45:07 INFO - 'NO_EM_RESTART': '1',
12:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:45:07 INFO - 'PWD': '/builds/slave/test',
12:45:07 INFO - 'SHELL': '/bin/bash',
12:45:07 INFO - 'SHLVL': '1',
12:45:07 INFO - 'TERM': 'linux',
12:45:07 INFO - 'TMOUT': '86400',
12:45:07 INFO - 'USER': 'cltbld',
12:45:07 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:45:07 INFO - '_': '/tools/buildbot/bin/python'}
12:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple
12:45:07 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
12:45:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
12:45:07 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
12:45:08 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:45:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
12:45:08 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:45:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
12:45:08 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:45:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
12:45:08 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:45:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
12:45:08 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:45:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
12:45:09 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:45:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
12:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:45:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
12:45:09 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:45:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
12:45:09 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:45:09 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
12:45:09 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:45:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
12:45:10 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:45:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
12:45:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
12:45:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
12:45:10 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:45:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
12:45:10 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:45:10 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
12:45:10 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:45:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
12:45:11 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:45:11 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:45:11 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:45:11 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:45:11 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:45:11 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:45:11 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
12:45:11 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
12:45:12 INFO - Installing collected packages: blessings
12:45:12 INFO - Successfully installed blessings-1.6
12:45:12 INFO - Return code: 0
12:45:12 INFO - Done creating virtualenv /builds/slave/test/build/venv.
12:45:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:45:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:45:12 INFO - Reading from file tmpfile_stdout
12:45:12 INFO - Reading from file tmpfile_stderr
12:45:12 INFO - Current package versions:
12:45:12 INFO - blessings == 1.6
12:45:12 INFO - blobuploader == 1.2.4
12:45:12 INFO - docopt == 0.6.1
12:45:12 INFO - functools32 == 3.2.3.post2
12:45:12 INFO - jsonschema == 2.5.1
12:45:12 INFO - manifestparser == 1.1
12:45:12 INFO - mozInstall == 1.13
12:45:12 INFO - mozcrash == 1.0
12:45:12 INFO - mozdebug == 0.1
12:45:12 INFO - mozdevice == 0.51
12:45:12 INFO - mozfile == 1.2
12:45:12 INFO - mozhttpd == 0.7
12:45:12 INFO - mozinfo == 0.10
12:45:12 INFO - mozleak == 0.1
12:45:12 INFO - mozlog == 3.5
12:45:12 INFO - moznetwork == 0.27
12:45:12 INFO - mozprocess == 0.25
12:45:12 INFO - mozprofile == 0.28
12:45:12 INFO - mozrunner == 6.13
12:45:12 INFO - mozscreenshot == 0.1
12:45:12 INFO - mozsystemmonitor == 0.3
12:45:12 INFO - moztest == 0.8
12:45:12 INFO - mozversion == 1.4
12:45:12 INFO - psutil == 3.1.1
12:45:12 INFO - requests == 1.2.3
12:45:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv
12:45: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:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:45: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:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:45:12 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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:45:12 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:45:12 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:45:12 INFO - Using env: {'DISPLAY': ':0',
12:45:12 INFO - 'HOME': '/home/cltbld',
12:45:12 INFO - 'LANG': 'en_US.UTF-8',
12:45:12 INFO - 'LANGUAGE': 'en_US:en',
12:45:12 INFO - 'LOGNAME': 'cltbld',
12:45:12 INFO - 'MAIL': '/var/mail/cltbld',
12:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:45:12 INFO - 'MOZ_NO_REMOTE': '1',
12:45:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:45:12 INFO - 'NO_EM_RESTART': '1',
12:45:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:45:12 INFO - 'PWD': '/builds/slave/test',
12:45:12 INFO - 'SHELL': '/bin/bash',
12:45:12 INFO - 'SHLVL': '1',
12:45:12 INFO - 'TERM': 'linux',
12:45:12 INFO - 'TMOUT': '86400',
12:45:12 INFO - 'USER': 'cltbld',
12:45:12 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:45:12 INFO - '_': '/tools/buildbot/bin/python'}
12:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple
12:45:13 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:45:13 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:45:13 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:45:13 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:45:13 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:45:13 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:45:13 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:45:13 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:45:13 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:45:13 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
12:45:14 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
12:45:15 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:45:15 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:45:15 INFO - Installing collected packages: simplejson, requests
12:45:15 INFO - Found existing installation: requests 1.2.3
12:45:15 INFO - Uninstalling requests-1.2.3:
12:45:15 INFO - Successfully uninstalled requests-1.2.3
12:45:15 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
12:45:16 INFO - Return code: 0
12:45:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
12:45: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:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
12:45: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:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
12:45:16 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 0x16431f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180da80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19b0cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, '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': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229', '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:45:16 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:45:16 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:45:16 INFO - Using env: {'DISPLAY': ':0',
12:45:16 INFO - 'HOME': '/home/cltbld',
12:45:16 INFO - 'LANG': 'en_US.UTF-8',
12:45:16 INFO - 'LANGUAGE': 'en_US:en',
12:45:16 INFO - 'LOGNAME': 'cltbld',
12:45:16 INFO - 'MAIL': '/var/mail/cltbld',
12:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:45:16 INFO - 'MOZ_NO_REMOTE': '1',
12:45:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:45:16 INFO - 'NO_EM_RESTART': '1',
12:45:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:45:16 INFO - 'PWD': '/builds/slave/test',
12:45:16 INFO - 'SHELL': '/bin/bash',
12:45:16 INFO - 'SHLVL': '1',
12:45:16 INFO - 'TERM': 'linux',
12:45:16 INFO - 'TMOUT': '86400',
12:45:16 INFO - 'USER': 'cltbld',
12:45:16 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:45:16 INFO - '_': '/tools/buildbot/bin/python'}
12:45:16 INFO - Ignoring indexes: https://pypi.python.org/simple
12:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
12:45:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
12:45:16 INFO - Return code: 0
12:45:16 INFO - Running post-action listener: _resource_record_post_action
12:45:16 INFO - Running post-action listener: _start_resource_monitoring
12:45:16 INFO - Starting resource monitoring.
12:45:16 INFO - [mozharness: 2017-10-22 19:45:16.542403Z] Finished create-virtualenv step (success)
12:45:16 INFO - [mozharness: 2017-10-22 19:45:16.542857Z] Running install step.
12:45:16 INFO - Running pre-action listener: _resource_record_pre_action
12:45:16 INFO - Running main action method: install
12:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
12:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
12:45:17 INFO - Reading from file tmpfile_stdout
12:45:17 INFO - Reading from file tmpfile_stderr
12:45:17 INFO - Detecting whether we're running mozinstall >=1.0...
12:45:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
12:45:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
12:45:17 INFO - Reading from file tmpfile_stdout
12:45:17 INFO - Output received:
12:45:17 INFO - Usage: mozinstall [options] installer
12:45:17 INFO - Options:
12:45:17 INFO - -h, --help show this help message and exit
12:45:17 INFO - -d DEST, --destination=DEST
12:45:17 INFO - Directory to install application into. [default:
12:45:17 INFO - "/builds/slave/test"]
12:45:17 INFO - --app=APP Application being installed. [default: firefox]
12:45:17 INFO - mkdir: /builds/slave/test/build/application
12:45:17 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:45:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
12:45:35 INFO - Reading from file tmpfile_stdout
12:45:35 INFO - Output received:
12:45:35 INFO - /builds/slave/test/build/application/firefox/firefox
12:45:35 INFO - Running post-action listener: _resource_record_post_action
12:45:35 INFO - [mozharness: 2017-10-22 19:45:35.242110Z] Finished install step (success)
12:45:35 INFO - [mozharness: 2017-10-22 19:45:35.242357Z] Running setup-mitmproxy step.
12:45:35 INFO - Running pre-action listener: _resource_record_pre_action
12:45:35 INFO - Running main action method: setup_mitmproxy
12:45:35 INFO - Skipping: mitmproxy is not required
12:45:35 INFO - Running post-action listener: _resource_record_post_action
12:45:35 INFO - [mozharness: 2017-10-22 19:45:35.243469Z] Finished setup-mitmproxy step (success)
12:45:35 INFO - [mozharness: 2017-10-22 19:45:35.243685Z] Running run-tests step.
12:45:35 INFO - Running pre-action listener: _resource_record_pre_action
12:45:35 INFO - Running pre-action listener: _set_gcov_prefix
12:45:35 INFO - Running main action method: run_tests
12:45:35 WARNING - Try message not found.
12:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
12:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
12:45:35 INFO - Python 2.7.3
12:45:35 INFO - Return code: 0
12:45:35 INFO - grabbing minidump binary from tooltool
12:45:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
12:45:35 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 0x19ade60>, '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 0x19aed60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19af1f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
12:45:35 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:45:35 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:45:35 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:45:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
12:45:35 INFO - Return code: 0
12:45:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
12:45:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
12:45:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
12:45:35 INFO - ENV: RUST_BACKTRACE is now full
12:45:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
12:45:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
12:45:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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:45:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-060 --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:45:35 INFO - Using env: {'DISPLAY': ':0',
12:45:35 INFO - 'HOME': '/home/cltbld',
12:45:35 INFO - 'LANG': 'en_US.UTF-8',
12:45:35 INFO - 'LANGUAGE': 'en_US:en',
12:45:35 INFO - 'LOGNAME': 'cltbld',
12:45:35 INFO - 'MAIL': '/var/mail/cltbld',
12:45:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:45:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:45:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:45:35 INFO - 'MOZ_NO_REMOTE': '1',
12:45:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:45:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:45:35 INFO - 'NO_EM_RESTART': '1',
12:45:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:45:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:45:35 INFO - 'PWD': '/builds/slave/test',
12:45:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:45:35 INFO - 'RUST_BACKTRACE': 'full',
12:45:35 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:45:35 INFO - 'SHELL': '/bin/bash',
12:45:35 INFO - 'SHLVL': '1',
12:45:35 INFO - 'TERM': 'linux',
12:45:35 INFO - 'TMOUT': '86400',
12:45:35 INFO - 'USER': 'cltbld',
12:45:35 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:45:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:45:35 INFO - '_': '/tools/buildbot/bin/python'}
12:45:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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:45:35 INFO - mozversion application_buildid: 20171022181033
12:45:35 INFO - mozversion application_changeset: a521d789a9d917251e70156049b653578490b799
12:45:35 INFO - mozversion application_display_name: Nightly
12:45:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
12:45:35 INFO - mozversion application_name: Firefox
12:45:35 INFO - mozversion application_remotingname: firefox
12:45:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
12:45:35 INFO - mozversion application_vendor: Mozilla
12:45:35 INFO - mozversion application_version: 58.0a1
12:45:35 INFO - mozversion platform_buildid: 20171022181033
12:45:35 INFO - mozversion platform_changeset: a521d789a9d917251e70156049b653578490b799
12:45:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
12:45:35 INFO - mozversion platform_version: 58.0a1
12:45:35 INFO - using testdate: 1508701535
12:45:35 INFO - actual date: 1508701535
12:45:35 INFO - starting webserver on 'localhost:41538'
12:45:35 INFO - SUITE-START | Running 2 tests
12:45:35 INFO - TEST-START | basic_compositor_video
12:45:35 INFO - Initialising browser for basic_compositor_video test...
12:45:35 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41538/getInfo.html -profile /tmp/tmp8fJWxk/profile
12:45:35 INFO - TEST-INFO | started process 3704 (/builds/slave/test/build/application/firefox/firefox http://localhost:41538/getInfo.html)
12:45:44 INFO - TEST-INFO | 3704: exit 0
12:45:44 INFO - Browser initialized.
12:45:44 INFO - Running cycle 1/1 for basic_compositor_video test...
12:45:44 INFO - Using env: {'DISPLAY': ':0',
12:45:44 INFO - 'HOME': '/home/cltbld',
12:45:44 INFO - 'JSGC_DISABLE_POISONING': '1',
12:45:44 INFO - 'LANG': 'en_US.UTF-8',
12:45:44 INFO - 'LANGUAGE': 'en_US:en',
12:45:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:45:44 INFO - 'LOGNAME': 'cltbld',
12:45:44 INFO - 'MAIL': '/var/mail/cltbld',
12:45:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:45:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:45:44 INFO - 'MOZ_CRASHREPORTER': '1',
12:45:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:45:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:45:44 INFO - 'MOZ_NO_REMOTE': '1',
12:45:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:45:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:45:44 INFO - 'NO_EM_RESTART': '1',
12:45:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:45:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:45:44 INFO - 'PWD': '/builds/slave/test',
12:45:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:45:44 INFO - 'RUST_BACKTRACE': 'full',
12:45:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:45:44 INFO - 'SHELL': '/bin/bash',
12:45:44 INFO - 'SHLVL': '1',
12:45:44 INFO - 'STYLO_FORCE_DISABLED': '1',
12:45:44 INFO - 'TERM': 'linux',
12:45:44 INFO - 'TMOUT': '86400',
12:45:44 INFO - 'USER': 'cltbld',
12:45:44 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:45:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:45:44 INFO - '_': '/tools/buildbot/bin/python'}
12:45:44 INFO - TEST-INFO | started process 3981 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8fJWxk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
12:45:45 INFO - PID 3981 |
12:45:45 INFO - PID 3981 | (/builds/slave/test/build/application/firefox/firefox:4032): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:45:45 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6802687569988801 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6726839464882945 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7977112043139605 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7516374781085806 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071628222523746 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.058133874239351 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.472771990740739 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4132593856655324 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.608672897196261 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634971830985908 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.380743944636677 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 10.363290155440422 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.278853383458644 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.053149171270732 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.825235042735056 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.35108024691358 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540173076923075 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.054281767955786 ms/frame
12:46:31 INFO - PID 3981 |
12:46:31 INFO - PID 3981 | Cycle 1(1): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:47:17 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6898986486486487 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689783152627187 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7913402985074627 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.777755555555556 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.017161981258367 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.81770038167939 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.4646420323325624 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.373102866779089 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.535184501845018 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.558930555555556 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.276386986301375 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 10.261051282051277 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239307116104884 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.112444444444433 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.044804347826089 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.275306748466258 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.952788844621491 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.495517241379318 ms/frame
12:47:17 INFO - PID 3981 |
12:47:17 INFO - PID 3981 | Cycle 1(2): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:48:03 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827818283791363 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6716708437761072 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7701681415929207 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7672084805653714 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.060825439783492 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.049200404858301 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.7087453646477155 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6041801801801796 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.780895953757225 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.651384180790961 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.492937062937061 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 9.853399014778315 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.407072243346013 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.755026881720418 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605063025210073 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.197743902439026 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.498275862068976 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.99032967032966 ms/frame
12:48:03 INFO - PID 3981 |
12:48:03 INFO - PID 3981 | Cycle 1(3): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:48:50 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.68030235162374 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.665578684429642 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7852766210588937 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.8151497277676951 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.167763888888888 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.831637931034482 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.4575806451612907 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4134470989761074 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715019047619052 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.51183195592286 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.381435986159172 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 10.205229591836726 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.279511278195484 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.586269841269843 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607731092436973 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.27334355828221 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.765686274509804 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.117222222222214 ms/frame
12:48:50 INFO - PID 3981 |
12:48:50 INFO - PID 3981 | Cycle 1(4): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:49:36 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6943873517786563 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684320266889077 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7382387022016221 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7205030094582972 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.059682002706362 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1854288702928875 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.5006767794632436 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4543350604490524 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.714990476190474 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.666572237960338 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 9.742840909090903 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 10.258102564102574 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.238127340823977 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.811918918918929 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.931487068965499 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.199847560975618 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49417624521074 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.700454545454548 ms/frame
12:49:36 INFO - PID 3981 |
12:49:36 INFO - PID 3981 | Cycle 1(5): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:50:22 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6803303471444566 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6700876460767946 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7721086828115773 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7202063628546858 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1785933147632335 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.025845070422534 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.5052219626168215 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.478747826086961 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.884 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.716042857142852 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 9.968272425249172 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 10.261153846153842 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.495057471264364 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81594594594593 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.768106382978713 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.770058823529432 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630193798449627 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.236123595505612 ms/frame
12:50:22 INFO - PID 3981 |
12:50:22 INFO - PID 3981 | Cycle 1(6): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:51:08 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6774538848518727 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689449541284405 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7807032640949554 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7778933333333342 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033145161290323 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9931237524950105 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.5134133489461377 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4919197207678865 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.557129629629627 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.780968208092486 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.530157894736833 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 9.75721951219512 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.278815789473674 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.933497267759572 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.768680851063857 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.423913043478262 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.858399209486178 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.176173184357522 ms/frame
12:51:08 INFO - PID 3981 |
12:51:08 INFO - PID 3981 | Cycle 1(7): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:51:54 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838439955106623 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6675416666666665 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7848988697204045 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.737862728062554 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.099945355191257 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.025603621730383 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.6956958128078825 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.631043557168787 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.758618042226491 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635042253521123 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.381176470588235 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 9.57093301435407 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.453721374045802 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.929808743169392 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.047630434782585 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.350802469135813 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586177606177605 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113444444444434 ms/frame
12:51:54 INFO - PID 3981 |
12:51:54 INFO - PID 3981 | Cycle 1(8): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:52:40 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6944297007340485 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6712823725981623 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7322257505773673 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.714682947729221 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9072135416666662 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.159812889812891 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.4611476355247985 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4033163265306086 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.587309124767223 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.58708100558659 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.831101083032491 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 10.641622340425531 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.279793233082705 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.874130434782629 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.766148936170206 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.425248447204947 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540403846153836 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113444444444434 ms/frame
12:52:40 INFO - PID 3981 |
12:52:40 INFO - PID 3981 | Cycle 1(9): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:53:27 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6905830985915493 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710108604845446 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.753930450029223 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7498818897637793 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 3.968373015873016 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.106755646817248 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.5887799043062216 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.491396160558464 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7587523992322485 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.68401988636364 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.382370242214536 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 9.951069651741298 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.196529850746263 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.644414893617029 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.715699152542369 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.053795180722894 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58666023166023 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.993461538461526 ms/frame
12:53:27 INFO - PID 3981 |
12:53:27 INFO - PID 3981 | Cycle 1(10): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:54:13 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6817937219730943 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669374999999997 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7477810133954574 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.714477292202227 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.088637602179836 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158367983367982 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.681147239263802 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5720267857142907 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.780789980732177 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.69877492877493 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.41934027777778 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 10.10383838383839 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.154832713754653 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.874945652173903 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.934978448275865 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.272883435582841 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.812913385826755 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.369289772727287 ms/frame
12:54:13 INFO - PID 3981 |
12:54:13 INFO - PID 3981 | Cycle 1(11): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:54:59 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6826388109927088 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668473728106756 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7709887839433296 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7497681539807513 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_startup = 4.202058823529413 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.108850102669405 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_startup = 3.554905213270142 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4377147766323026 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.474927007299274 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.466748633879776 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_startup = 10.604275618374565 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.480p.60fps.webm_scale_2_inclip = 9.90383663366337 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.412262357414459 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.753924731182774 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.556799163179905 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.976706586826351 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58308880308879 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.2400561797753 ms/frame
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | Cycle 1(12): loaded http://localhost:41538/tests/video/video_playback.html (next: http://localhost:41538/tests/video/video_playback.html)
12:54:59 INFO - PID 3981 | __start_tp_report
12:54:59 INFO - PID 3981 | _x_x_mozilla_page_load
12:54:59 INFO - PID 3981 | _x_x_mozilla_page_load_details
12:54:59 INFO - PID 3981 | |i|pagename|runs|
12:54:59 INFO - PID 3981 | |0;240p.120fps.mp4_scale_1_startup;1.6802687569988801;1.6898986486486487;1.6827818283791363;1.68030235162374;1.6943873517786563;1.6803303471444566;1.6774538848518727;1.6838439955106623;1.6944297007340485;1.6905830985915493;1.6817937219730943;1.6826388109927088
12:54:59 INFO - PID 3981 | |1;240p.120fps.mp4_scale_1_inclip;1.6726839464882945;1.6689783152627187;1.6716708437761072;1.665578684429642;1.6684320266889077;1.6700876460767946;1.6689449541284405;1.6675416666666665;1.6712823725981623;1.6710108604845446;1.6669374999999997;1.668473728106756
12:54:59 INFO - PID 3981 | |2;240p.120fps.mp4_scale_1.1_startup;1.7977112043139605;1.7913402985074627;1.7701681415929207;1.7852766210588937;1.7382387022016221;1.7721086828115773;1.7807032640949554;1.7848988697204045;1.7322257505773673;1.753930450029223;1.7477810133954574;1.7709887839433296
12:54:59 INFO - PID 3981 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7516374781085806;1.777755555555556;1.7672084805653714;1.8151497277676951;1.7205030094582972;1.7202063628546858;1.7778933333333342;1.737862728062554;1.714682947729221;1.7498818897637793;1.714477292202227;1.7497681539807513
12:54:59 INFO - PID 3981 | |4;240p.120fps.mp4_scale_2_startup;4.071628222523746;4.017161981258367;4.060825439783492;4.167763888888888;4.059682002706362;4.1785933147632335;4.033145161290323;4.099945355191257;3.9072135416666662;3.968373015873016;4.088637602179836;4.202058823529413
12:54:59 INFO - PID 3981 | |5;240p.120fps.mp4_scale_2_inclip;4.058133874239351;3.81770038167939;4.049200404858301;3.831637931034482;4.1854288702928875;4.025845070422534;3.9931237524950105;4.025603621730383;4.159812889812891;4.106755646817248;4.158367983367982;4.108850102669405
12:54:59 INFO - PID 3981 | |6;480p.60fps.webm_scale_1_startup;3.472771990740739;3.4646420323325624;3.7087453646477155;3.4575806451612907;3.5006767794632436;3.5052219626168215;3.5134133489461377;3.6956958128078825;3.4611476355247985;3.5887799043062216;3.681147239263802;3.554905213270142
12:54:59 INFO - PID 3981 | |7;480p.60fps.webm_scale_1_inclip;3.4132593856655324;3.373102866779089;3.6041801801801796;3.4134470989761074;3.4543350604490524;3.478747826086961;3.4919197207678865;3.631043557168787;3.4033163265306086;3.491396160558464;3.5720267857142907;3.4377147766323026
12:54:59 INFO - PID 3981 | |8;480p.60fps.webm_scale_1.1_startup;5.608672897196261;5.535184501845018;5.780895953757225;5.715019047619052;5.714990476190474;5.884;5.557129629629627;5.758618042226491;5.587309124767223;5.7587523992322485;5.780789980732177;5.474927007299274
12:54:59 INFO - PID 3981 | |9;480p.60fps.webm_scale_1.1_inclip;5.634971830985908;5.558930555555556;5.651384180790961;5.51183195592286;5.666572237960338;5.716042857142852;5.780968208092486;5.635042253521123;5.58708100558659;5.68401988636364;5.69877492877493;5.466748633879776
12:54:59 INFO - PID 3981 | |10;480p.60fps.webm_scale_2_startup;10.380743944636677;10.276386986301375;10.492937062937061;10.381435986159172;9.742840909090903;9.968272425249172;10.530157894736833;10.381176470588235;10.831101083032491;10.382370242214536;10.41934027777778;10.604275618374565
12:54:59 INFO - PID 3981 | |11;480p.60fps.webm_scale_2_inclip;10.363290155440422;10.261051282051277;9.853399014778315;10.205229591836726;10.258102564102574;10.261153846153842;9.75721951219512;9.57093301435407;10.641622340425531;9.951069651741298;10.10383838383839;9.90383663366337
12:54:59 INFO - PID 3981 | |12;1080p.60fps.mp4_scale_1_startup;11.278853383458644;11.239307116104884;11.407072243346013;11.279511278195484;11.238127340823977;11.495057471264364;11.278815789473674;11.453721374045802;11.279793233082705;11.196529850746263;11.154832713754653;11.412262357414459
12:54:59 INFO - PID 3981 | |13;1080p.60fps.mp4_scale_1_inclip;11.053149171270732;11.112444444444433;10.755026881720418;10.586269841269843;10.811918918918929;10.81594594594593;10.933497267759572;10.929808743169392;10.874130434782629;10.644414893617029;10.874945652173903;10.753924731182774
12:54:59 INFO - PID 3981 | |14;1080p.60fps.mp4_scale_1.1_startup;12.825235042735056;13.044804347826089;12.605063025210073;12.607731092436973;12.931487068965499;12.768106382978713;12.768680851063857;13.047630434782585;12.766148936170206;12.715699152542369;12.934978448275865;12.556799163179905
12:54:59 INFO - PID 3981 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.35108024691358;12.275306748466258;12.197743902439026;12.27334355828221;12.199847560975618;11.770058823529432;12.423913043478262;12.350802469135813;12.425248447204947;12.053795180722894;12.272883435582841;11.976706586826351
12:54:59 INFO - PID 3981 | |16;1080p.60fps.mp4_scale_2_startup;11.540173076923075;11.952788844621491;11.498275862068976;11.765686274509804;11.49417624521074;11.630193798449627;11.858399209486178;11.586177606177605;11.540403846153836;11.58666023166023;11.812913385826755;11.58308880308879
12:54:59 INFO - PID 3981 | |17;1080p.60fps.mp4_scale_2_inclip;11.054281767955786;11.495517241379318;10.99032967032966;11.117222222222214;10.700454545454548;11.236123595505612;11.176173184357522;11.113444444444434;11.113444444444434;10.993461538461526;11.369289772727287;11.2400561797753
12:54:59 INFO - PID 3981 | __end_tp_report
12:54:59 INFO - PID 3981 | __start_cc_report
12:54:59 INFO - PID 3981 | _x_x_mozilla_cycle_collect,557
12:54:59 INFO - PID 3981 | __end_cc_report
12:54:59 INFO - PID 3981 | __startTimestamp1508702099646__endTimestamp
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | ------- Summary: start -------
12:54:59 INFO - PID 3981 | Number of tests: 18
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.01 (0.3%) stddev-sans-first:0.01
12:54:59 INFO - PID 3981 | 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:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
12:54:59 INFO - PID 3981 | 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:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.77 Median:1.78 stddev:0.02 (1.2%) stddev-sans-first:0.02
12:54:59 INFO - PID 3981 | Values: 1.8 1.8 1.8 1.8 1.7 1.8 1.8 1.8 1.7 1.8 1.7 1.8
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.75 stddev:0.03 (1.8%) stddev-sans-first:0.03
12:54:59 INFO - PID 3981 | Values: 1.8 1.8 1.8 1.8 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.07 Median:4.08 stddev:0.09 (2.1%) stddev-sans-first:0.09
12:54:59 INFO - PID 3981 | Values: 4.1 4.0 4.1 4.2 4.1 4.2 4.0 4.1 3.9 4.0 4.1 4.2
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.04 Median:4.08 stddev:0.12 (2.9%) stddev-sans-first:0.12
12:54:59 INFO - PID 3981 | Values: 4.1 3.8 4.0 3.8 4.2 4.0 4.0 4.0 4.2 4.1 4.2 4.1
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.55 Median:3.53 stddev:0.10 (2.7%) stddev-sans-first:0.10
12:54:59 INFO - PID 3981 | Values: 3.5 3.5 3.7 3.5 3.5 3.5 3.5 3.7 3.5 3.6 3.7 3.6
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.48 Median:3.49 stddev:0.08 (2.4%) stddev-sans-first:0.08
12:54:59 INFO - PID 3981 | Values: 3.4 3.4 3.6 3.4 3.5 3.5 3.5 3.6 3.4 3.5 3.6 3.4
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.68 Median:5.74 stddev:0.12 (2.2%) stddev-sans-first:0.13
12:54:59 INFO - PID 3981 | Values: 5.6 5.5 5.8 5.7 5.7 5.9 5.6 5.8 5.6 5.8 5.8 5.5
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.63 Median:5.66 stddev:0.09 (1.6%) stddev-sans-first:0.09
12:54:59 INFO - PID 3981 | Values: 5.6 5.6 5.7 5.5 5.7 5.7 5.8 5.6 5.6 5.7 5.7 5.5
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.37 Median:10.51 stddev:0.28 (2.7%) stddev-sans-first:0.30
12:54:59 INFO - PID 3981 | Values: 10.4 10.3 10.5 10.4 9.7 10.0 10.5 10.4 10.8 10.4 10.4 10.6
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.09 Median:10.11 stddev:0.30 (2.9%) stddev-sans-first:0.30
12:54:59 INFO - PID 3981 | Values: 10.4 10.3 9.9 10.2 10.3 10.3 9.8 9.6 10.6 10.0 10.1 9.9
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.31 Median:11.28 stddev:0.11 (0.9%) stddev-sans-first:0.11
12:54:59 INFO - PID 3981 | Values: 11.3 11.2 11.4 11.3 11.2 11.5 11.3 11.5 11.3 11.2 11.2 11.4
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.85 Median:10.87 stddev:0.15 (1.4%) stddev-sans-first:0.14
12:54:59 INFO - PID 3981 | Values: 11.1 11.1 10.8 10.6 10.8 10.8 10.9 10.9 10.9 10.6 10.9 10.8
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.80 Median:12.80 stddev:0.17 (1.3%) stddev-sans-first:0.17
12:54:59 INFO - PID 3981 | Values: 12.8 13.0 12.6 12.6 12.9 12.8 12.8 13.0 12.8 12.7 12.9 12.6
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.21 Median:12.27 stddev:0.19 (1.6%) stddev-sans-first:0.20
12:54:59 INFO - PID 3981 | Values: 12.4 12.3 12.2 12.3 12.2 11.8 12.4 12.4 12.4 12.1 12.3 12.0
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.65 Median:11.61 stddev:0.15 (1.3%) stddev-sans-first:0.16
12:54:59 INFO - PID 3981 | Values: 11.5 12.0 11.5 11.8 11.5 11.6 11.9 11.6 11.5 11.6 11.8 11.6
12:54:59 INFO - PID 3981 |
12:54:59 INFO - PID 3981 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.13 Median:11.15 stddev:0.20 (1.8%) stddev-sans-first:0.21
12:54:59 INFO - PID 3981 | Values: 11.1 11.5 11.0 11.1 10.7 11.2 11.2 11.1 11.1 11.0 11.4 11.2
12:54:59 INFO - PID 3981 | -------- Summary: end --------
12:54:59 INFO - PID 3981 |
12:55:00 INFO - TEST-INFO | 3981: exit 0
12:55:00 INFO - TEST-OK | basic_compositor_video | took 564296ms
12:55:00 INFO - TEST-START | glvideo
12:55:00 INFO - Initialising browser for glvideo test...
12:55:00 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:41538/getInfo.html -profile /tmp/tmpF5yDLM/profile
12:55:00 INFO - TEST-INFO | started process 5224 (/builds/slave/test/build/application/firefox/firefox http://localhost:41538/getInfo.html)
12:55:05 INFO - TEST-INFO | 5224: exit 0
12:55:06 INFO - Browser initialized.
12:55:06 INFO - Running cycle 1/1 for glvideo test...
12:55:06 INFO - Using env: {'DISPLAY': ':0',
12:55:06 INFO - 'HOME': '/home/cltbld',
12:55:06 INFO - 'JSGC_DISABLE_POISONING': '1',
12:55:06 INFO - 'LANG': 'en_US.UTF-8',
12:55:06 INFO - 'LANGUAGE': 'en_US:en',
12:55:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
12:55:06 INFO - 'LOGNAME': 'cltbld',
12:55:06 INFO - 'MAIL': '/var/mail/cltbld',
12:55:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
12:55:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
12:55:06 INFO - 'MOZ_CRASHREPORTER': '1',
12:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
12:55:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
12:55:06 INFO - 'MOZ_NO_REMOTE': '1',
12:55:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
12:55:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
12:55:06 INFO - 'NO_EM_RESTART': '1',
12:55:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
12:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
12:55:06 INFO - 'PWD': '/builds/slave/test',
12:55:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
12:55:06 INFO - 'RUST_BACKTRACE': 'full',
12:55:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
12:55:06 INFO - 'SHELL': '/bin/bash',
12:55:06 INFO - 'SHLVL': '1',
12:55:06 INFO - 'STYLO_FORCE_DISABLED': '1',
12:55:06 INFO - 'TERM': 'linux',
12:55:06 INFO - 'TMOUT': '86400',
12:55:06 INFO - 'USER': 'cltbld',
12:55:06 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229',
12:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
12:55:06 INFO - '_': '/tools/buildbot/bin/python'}
12:55:06 INFO - TEST-INFO | started process 5501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpF5yDLM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
12:55:06 INFO - PID 5501 |
12:55:06 INFO - PID 5501 | (/builds/slave/test/build/application/firefox/firefox:5552): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
12:55:06 INFO - PID 5501 |
12:55:08 INFO - PID 5501 | [talos glvideo result] Mean tick time across 100 ticks: 7.34965 ms
12:55:08 INFO - PID 5501 | Cycle 1(1): loaded http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html)
12:55:09 INFO - PID 5501 | [talos glvideo result] Mean tick time across 100 ticks: 6.445399999999999 ms
12:55:09 INFO - PID 5501 | Cycle 1(2): loaded http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html)
12:55:11 INFO - PID 5501 | [talos glvideo result] Mean tick time across 100 ticks: 6.479150000000001 ms
12:55:11 INFO - PID 5501 | Cycle 1(3): loaded http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html)
12:55:12 INFO - PID 5501 | [talos glvideo result] Mean tick time across 100 ticks: 6.6293500000000005 ms
12:55:12 INFO - PID 5501 | Cycle 1(4): loaded http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html)
12:55:13 INFO - PID 5501 | [talos glvideo result] Mean tick time across 100 ticks: 6.6168000000000005 ms
12:55:13 INFO - PID 5501 | Cycle 1(5): loaded http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:41538/tests/webgl/benchmarks/video/video_upload.html)
12:55:13 INFO - PID 5501 | __start_tp_report
12:55:13 INFO - PID 5501 | _x_x_mozilla_page_load
12:55:13 INFO - PID 5501 | _x_x_mozilla_page_load_details
12:55:13 INFO - PID 5501 | |i|pagename|runs|
12:55:13 INFO - PID 5501 | |0;Mean tick time across 100 ticks: ;7.34965;6.445399999999999;6.479150000000001;6.6293500000000005;6.6168000000000005
12:55:13 INFO - PID 5501 | __end_tp_report
12:55:13 INFO - PID 5501 | __start_cc_report
12:55:13 INFO - PID 5501 | _x_x_mozilla_cycle_collect,272
12:55:13 INFO - PID 5501 | __end_cc_report
12:55:13 INFO - PID 5501 | __startTimestamp1508702113928__endTimestamp
12:55:13 INFO - PID 5501 |
12:55:13 INFO - PID 5501 | ------- Summary: start -------
12:55:13 INFO - PID 5501 | Number of tests: 1
12:55:13 INFO - PID 5501 |
12:55:13 INFO - PID 5501 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.70 Median:6.62 stddev:0.37 (5.6%) stddev-sans-first:0.09
12:55:13 INFO - PID 5501 | Values: 7.3 6.4 6.5 6.6 6.6
12:55:13 INFO - PID 5501 | -------- Summary: end --------
12:55:13 INFO - PID 5501 |
12:55:14 INFO - PID 5501 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
12:55:14 INFO - TEST-INFO | 5501: exit 0
12:55:14 INFO - TEST-OK | glvideo | took 14290ms
12:55:14 INFO - SUITE-END | took 578s
12:55:14 INFO - Completed test suite (00:09:39)
12:55:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.647992418608053, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6802687569988801, 1.6898986486486487, 1.6827818283791363, 1.68030235162374, 1.6943873517786563, 1.6803303471444566, 1.6774538848518727, 1.6838439955106623, 1.6944297007340485, 1.6905830985915493, 1.6817937219730943, 1.6826388109927088], "value": 1.6827818283791363, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6726839464882945, 1.6689783152627187, 1.6716708437761072, 1.665578684429642, 1.6684320266889077, 1.6700876460767946, 1.6689449541284405, 1.6675416666666665, 1.6712823725981623, 1.6710108604845446, 1.6669374999999997, 1.668473728106756], "value": 1.6689449541284405, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7977112043139605, 1.7913402985074627, 1.7701681415929207, 1.7852766210588937, 1.7382387022016221, 1.7721086828115773, 1.7807032640949554, 1.7848988697204045, 1.7322257505773673, 1.753930450029223, 1.7477810133954574, 1.7709887839433296], "value": 1.7709887839433296, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7516374781085806, 1.777755555555556, 1.7672084805653714, 1.8151497277676951, 1.7205030094582972, 1.7202063628546858, 1.7778933333333342, 1.737862728062554, 1.714682947729221, 1.7498818897637793, 1.714477292202227, 1.7497681539807513], "value": 1.7497681539807513, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.071628222523746, 4.017161981258367, 4.060825439783492, 4.167763888888888, 4.059682002706362, 4.1785933147632335, 4.033145161290323, 4.099945355191257, 3.9072135416666662, 3.968373015873016, 4.088637602179836, 4.202058823529413], "value": 4.060825439783492, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.058133874239351, 3.81770038167939, 4.049200404858301, 3.831637931034482, 4.1854288702928875, 4.025845070422534, 3.9931237524950105, 4.025603621730383, 4.159812889812891, 4.106755646817248, 4.158367983367982, 4.108850102669405], "value": 4.049200404858301, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.472771990740739, 3.4646420323325624, 3.7087453646477155, 3.4575806451612907, 3.5006767794632436, 3.5052219626168215, 3.5134133489461377, 3.6956958128078825, 3.4611476355247985, 3.5887799043062216, 3.681147239263802, 3.554905213270142], "value": 3.5134133489461377, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4132593856655324, 3.373102866779089, 3.6041801801801796, 3.4134470989761074, 3.4543350604490524, 3.478747826086961, 3.4919197207678865, 3.631043557168787, 3.4033163265306086, 3.491396160558464, 3.5720267857142907, 3.4377147766323026], "value": 3.478747826086961, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.608672897196261, 5.535184501845018, 5.780895953757225, 5.715019047619052, 5.714990476190474, 5.884, 5.557129629629627, 5.758618042226491, 5.587309124767223, 5.7587523992322485, 5.780789980732177, 5.474927007299274], "value": 5.715019047619052, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.634971830985908, 5.558930555555556, 5.651384180790961, 5.51183195592286, 5.666572237960338, 5.716042857142852, 5.780968208092486, 5.635042253521123, 5.58708100558659, 5.68401988636364, 5.69877492877493, 5.466748633879776], "value": 5.651384180790961, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.380743944636677, 10.276386986301375, 10.492937062937061, 10.381435986159172, 9.742840909090903, 9.968272425249172, 10.530157894736833, 10.381176470588235, 10.831101083032491, 10.382370242214536, 10.41934027777778, 10.604275618374565], "value": 10.382370242214536, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.363290155440422, 10.261051282051277, 9.853399014778315, 10.205229591836726, 10.258102564102574, 10.261153846153842, 9.75721951219512, 9.57093301435407, 10.641622340425531, 9.951069651741298, 10.10383838383839, 9.90383663366337], "value": 10.10383838383839, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.278853383458644, 11.239307116104884, 11.407072243346013, 11.279511278195484, 11.238127340823977, 11.495057471264364, 11.278815789473674, 11.453721374045802, 11.279793233082705, 11.196529850746263, 11.154832713754653, 11.412262357414459], "value": 11.279511278195484, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.053149171270732, 11.112444444444433, 10.755026881720418, 10.586269841269843, 10.811918918918929, 10.81594594594593, 10.933497267759572, 10.929808743169392, 10.874130434782629, 10.644414893617029, 10.874945652173903, 10.753924731182774], "value": 10.81594594594593, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.825235042735056, 13.044804347826089, 12.605063025210073, 12.607731092436973, 12.931487068965499, 12.768106382978713, 12.768680851063857, 13.047630434782585, 12.766148936170206, 12.715699152542369, 12.934978448275865, 12.556799163179905], "value": 12.768106382978713, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.35108024691358, 12.275306748466258, 12.197743902439026, 12.27334355828221, 12.199847560975618, 11.770058823529432, 12.423913043478262, 12.350802469135813, 12.425248447204947, 12.053795180722894, 12.272883435582841, 11.976706586826351], "value": 12.272883435582841, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.540173076923075, 11.952788844621491, 11.498275862068976, 11.765686274509804, 11.49417624521074, 11.630193798449627, 11.858399209486178, 11.586177606177605, 11.540403846153836, 11.58666023166023, 11.812913385826755, 11.58308880308879], "value": 11.58666023166023, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.054281767955786, 11.495517241379318, 10.99032967032966, 11.117222222222214, 10.700454545454548, 11.236123595505612, 11.176173184357522, 11.113444444444434, 11.113444444444434, 10.993461538461526, 11.369289772727287, 11.2400561797753], "value": 11.117222222222214, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.34965, 6.445399999999999, 6.479150000000001, 6.6293500000000005, 6.6168000000000005], "value": 6.547975000000001, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
12:55:14 INFO - Return code: 0
12:55:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
12:55:15 INFO - # TBPL SUCCESS #
12:55:15 INFO - Running post-action listener: _package_coverage_data
12:55:15 INFO - Running post-action listener: _resource_record_post_action
12:55:15 INFO - [mozharness: 2017-10-22 19:55:15.071267Z] Finished run-tests step (success)
12:55:15 INFO - Running post-run listener: _resource_record_post_run
12:55:15 INFO - Total resource usage - Wall time: 598s; CPU: 38.0%; Read bytes: 18087936; Write bytes: 311762944; Read time: 3308; Write time: 251796
12:55:15 INFO - TinderboxPrint: CPU usage
38.3%
12:55:15 INFO - TinderboxPrint: I/O read bytes / time
18,087,936 / 3,308
12:55:15 INFO - TinderboxPrint: I/O write bytes / time
311,762,944 / 251,796
12:55:15 INFO - TinderboxPrint: CPU idle
2,892.5 (61.6%)
12:55:15 INFO - TinderboxPrint: CPU system
303.7 (6.5%)
12:55:15 INFO - TinderboxPrint: CPU user
1,477.7 (31.5%)
12:55:15 INFO - TinderboxPrint: Swap in / out
0 / 0
12:55:15 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 3665920; Read time: 0; Write time: 172
12:55:15 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
12:55:15 INFO - run-tests - Wall time: 580s; CPU: 39.0%; Read bytes: 16326656; Write bytes: 288063488; Read time: 3136; Write time: 242596
12:55:15 INFO - Running post-run listener: _upload_blobber_files
12:55:15 INFO - Blob upload gear active.
12:55:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
12:55:15 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:55:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
12:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
12:55:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:334: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:15 INFO - SNIMissingWarning
12:55:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:15 INFO - InsecurePlatformWarning
12:55:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:55:15 INFO - SubjectAltNameWarning
12:55:15 INFO - (blobuploader) - INFO - Open directory for files ...
12:55:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
12:55:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:55:15 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:55:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:15 INFO - InsecurePlatformWarning
12:55:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:55:16 INFO - SubjectAltNameWarning
12:55:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:16 INFO - InsecurePlatformWarning
12:55:16 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
12:55:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:55:16 INFO - (blobuploader) - INFO - Done attempting.
12:55:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
12:55:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:55:16 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:55:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:16 INFO - InsecurePlatformWarning
12:55:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:55:16 INFO - SubjectAltNameWarning
12:55:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:17 INFO - InsecurePlatformWarning
12:55:17 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
12:55:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:55:17 INFO - (blobuploader) - INFO - Done attempting.
12:55:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
12:55:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:55:17 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:55:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:17 INFO - InsecurePlatformWarning
12:55:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:55:17 INFO - SubjectAltNameWarning
12:55:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:18 INFO - InsecurePlatformWarning
12:55:18 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
12:55:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:55:18 INFO - (blobuploader) - INFO - Done attempting.
12:55:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
12:55:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
12:55:18 INFO - (blobuploader) - INFO - Uploading, attempt #1.
12:55:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:18 INFO - InsecurePlatformWarning
12:55:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
12:55:18 INFO - SubjectAltNameWarning
12:55:23 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
12:55:23 INFO - InsecurePlatformWarning
12:55:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
12:55:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
12:55:23 INFO - (blobuploader) - INFO - Done attempting.
12:55:23 INFO - (blobuploader) - INFO - Iteration through files over.
12:55:23 INFO - Return code: 0
12:55:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
12:55:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
12:55:23 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31210cf6dfc1ccf5d324680e20389a5dacc5596ab221b53423c447c9fba8e2e024a8084df02ec894f19d8bc532e183c98c2433275d71d3e4c154844657304f56", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6751a0eb3b0d01da4df32bc036a33269209e10e13dec4fed21088e2ddb9c13e244e7ad40a1dff9c77b430cb268fa882adac8f9cfd24b47724735ca1a28d1d11", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d79bb741b977d5e6d178888d416921b3481c2f12d681488f8429dd83e10565701d610f53370e064ff93df517301741579a51abc2ff5c81b97028fcc9d8cdfe01", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5725c9c5c479cfcb2f8affcc4210a7c622974d5ec36998108fb983d946cf639b5792aa32a0a2b64b859f33d1e9bef7f08e21c5128d1ca9707d6113ea23a114f1"}
12:55:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
12:55:23 INFO - Writing to file /builds/slave/test/properties/blobber_files
12:55:23 INFO - Contents:
12:55:23 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31210cf6dfc1ccf5d324680e20389a5dacc5596ab221b53423c447c9fba8e2e024a8084df02ec894f19d8bc532e183c98c2433275d71d3e4c154844657304f56", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6751a0eb3b0d01da4df32bc036a33269209e10e13dec4fed21088e2ddb9c13e244e7ad40a1dff9c77b430cb268fa882adac8f9cfd24b47724735ca1a28d1d11", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d79bb741b977d5e6d178888d416921b3481c2f12d681488f8429dd83e10565701d610f53370e064ff93df517301741579a51abc2ff5c81b97028fcc9d8cdfe01", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5725c9c5c479cfcb2f8affcc4210a7c622974d5ec36998108fb983d946cf639b5792aa32a0a2b64b859f33d1e9bef7f08e21c5128d1ca9707d6113ea23a114f1"}
12:55:23 INFO - Running post-run listener: copy_logs_to_upload_dir
12:55:23 INFO - Copying logs to upload dir...
12:55:23 INFO - mkdir: /builds/slave/test/build/upload/logs
12:55:23 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=659.355415
========= master_lag: 0.15 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 59 secs) (at 2017-10-22 12:55:23.797093) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-22 12:55:23.804866) =========
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=337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31210cf6dfc1ccf5d324680e20389a5dacc5596ab221b53423c447c9fba8e2e024a8084df02ec894f19d8bc532e183c98c2433275d71d3e4c154844657304f56", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6751a0eb3b0d01da4df32bc036a33269209e10e13dec4fed21088e2ddb9c13e244e7ad40a1dff9c77b430cb268fa882adac8f9cfd24b47724735ca1a28d1d11", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d79bb741b977d5e6d178888d416921b3481c2f12d681488f8429dd83e10565701d610f53370e064ff93df517301741579a51abc2ff5c81b97028fcc9d8cdfe01", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5725c9c5c479cfcb2f8affcc4210a7c622974d5ec36998108fb983d946cf639b5792aa32a0a2b64b859f33d1e9bef7f08e21c5128d1ca9707d6113ea23a114f1"}
build_url:https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011303
build_url: 'https://queue.taskcluster.net/v1/task/fRd2fq4HQY6YXQCub_Q35w/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/31210cf6dfc1ccf5d324680e20389a5dacc5596ab221b53423c447c9fba8e2e024a8084df02ec894f19d8bc532e183c98c2433275d71d3e4c154844657304f56", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e6751a0eb3b0d01da4df32bc036a33269209e10e13dec4fed21088e2ddb9c13e244e7ad40a1dff9c77b430cb268fa882adac8f9cfd24b47724735ca1a28d1d11", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d79bb741b977d5e6d178888d416921b3481c2f12d681488f8429dd83e10565701d610f53370e064ff93df517301741579a51abc2ff5c81b97028fcc9d8cdfe01", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5725c9c5c479cfcb2f8affcc4210a7c622974d5ec36998108fb983d946cf639b5792aa32a0a2b64b859f33d1e9bef7f08e21c5128d1ca9707d6113ea23a114f1"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-22 12:55:23.834519) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-22 12:55:23.834845) =========
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=337fe3f4b1febe37b85fccc40000019e-1508700521.980996-1127287229
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004454
========= master_lag: 0.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-22 12:55:23.889701) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-22 12:55:23.890028) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-22 12:55:23.890332) =========
========= Total master_lag: 0.32 =========