builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s
slave: talos-linux64-ix-026
starttime: 1507368925.42
results: success (0)
revision: dde44491690467f0b59a942e5565ba5d3eed69de
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.420673) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.421125) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.421542) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.584713) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.584983) =========
bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-07 02:35:25-- 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% 26.5M=0s
2017-10-07 02:35:25 (26.5 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.078232
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.725839) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.726165) =========
rm -rf scripts properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.024703
========= master_lag: 0.06 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.808473) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:25.808787) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dde44491690467f0b59a942e5565ba5d3eed69de --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 dde44491690467f0b59a942e5565ba5d3eed69de --destination scripts --debug']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-07 02:35:25,891 truncating revision to first 12 chars
2017-10-07 02:35:25,891 Setting DEBUG logging.
2017-10-07 02:35:25,891 attempt 1/10
2017-10-07 02:35:25,891 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dde444916904?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-07 02:35:26,344 unpacking tar archive at: mozilla-central-dde444916904/testing/mozharness/
program finished with exit code 0
elapsedTime=0.757364
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:26.605771) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:26.606069) =========
script_repo_revision: dde44491690467f0b59a942e5565ba5d3eed69de
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:26.609473) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:26.609736) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-07 02:35:26.623901) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 45 secs) (at 2017-10-07 02:35:26.624219) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
02:35:26 INFO - MultiFileLogger online at 20171007 02:35:26 in /builds/slave/test
02:35:26 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
02:35:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
02:35:26 INFO - {'append_to_log': False,
02:35:26 INFO - 'base_work_dir': '/builds/slave/test',
02:35:26 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
02:35:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
02:35:26 INFO - 'branch': 'Firefox-Non-PGO',
02:35:26 INFO - 'buildbot_json_path': 'buildprops.json',
02:35:26 INFO - 'code_coverage': False,
02:35:26 INFO - 'config_files': ('talos/linux_config.py',),
02:35:26 INFO - 'default_actions': ('clobber',
02:35:26 INFO - 'read-buildbot-config',
02:35:26 INFO - 'download-and-extract',
02:35:26 INFO - 'populate-webroot',
02:35:26 INFO - 'create-virtualenv',
02:35:26 INFO - 'install',
02:35:26 INFO - 'setup-mitmproxy',
02:35:26 INFO - 'run-tests'),
02:35:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:35:26 INFO - 'disable_ccov_upload': False,
02:35:26 INFO - 'disable_stylo': False,
02:35:26 INFO - 'download_minidump_stackwalk': True,
02:35:26 INFO - 'download_symbols': 'ondemand',
02:35:26 INFO - 'enable_stylo': False,
02:35:26 INFO - 'enable_webrender': False,
02:35:26 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
02:35:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:35:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:35:26 INFO - 'gecko_profile': False,
02:35:26 INFO - 'gecko_profile_interval': 0,
02:35:26 INFO - 'installer_path': 'installer.exe',
02:35:26 INFO - 'log_level': 'info',
02:35:26 INFO - 'log_name': 'talos',
02:35:26 INFO - 'log_to_console': True,
02:35:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
02:35:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
02:35:26 INFO - 'opt_config_files': (),
02:35:26 INFO - 'pip_index': False,
02:35:26 INFO - 'suite': 'g4-stylo-disabled-e10s',
02:35:26 INFO - 'system_bits': '32',
02:35:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:35:26 INFO - 'title': 'talos-linux64-ix-026',
02:35:26 INFO - 'tooltool_cache': '/builds/tooltool_cache',
02:35:26 INFO - 'use_talos_json': True,
02:35:26 INFO - 'verify': 'False',
02:35:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
02:35:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:35:26 INFO - 'work_dir': 'build'}
02:35:26 INFO - [mozharness: 2017-10-07 09:35:26.830424Z] Running clobber step.
02:35:26 INFO - Running pre-action listener: _resource_record_pre_action
02:35:26 INFO - Running main action method: clobber
02:35:26 INFO - rmtree: /builds/slave/test/build
02:35:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
02:35:27 INFO - Running post-action listener: _resource_record_post_action
02:35:27 INFO - [mozharness: 2017-10-07 09:35:27.826354Z] Finished clobber step (success)
02:35:27 INFO - [mozharness: 2017-10-07 09:35:27.826453Z] Running read-buildbot-config step.
02:35:27 INFO - Running pre-action listener: _resource_record_pre_action
02:35:27 INFO - Running main action method: read_buildbot_config
02:35:27 INFO - Using buildbot properties:
02:35:27 INFO - {
02:35:27 INFO - "project": "",
02:35:27 INFO - "product": "firefox",
02:35:27 INFO - "who": "archaeopteryx@coole-files.de",
02:35:27 INFO - "installer_path": "public/build/target.tar.bz2",
02:35:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
02:35:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
02:35:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-stylo-disabled-e10s",
02:35:27 INFO - "stage_platform": "linux64",
02:35:27 INFO - "basedir": "/builds/slave/test",
02:35:27 INFO - "buildnumber": 80,
02:35:27 INFO - "platform": "ubuntu64_hw",
02:35:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
02:35:27 INFO - "slavebuilddir": "test",
02:35:27 INFO - "taskId": "VAS2L2K7Qja5Jo8FIPfjJA",
02:35:27 INFO - "branch": "mozilla-central",
02:35:27 INFO - "script_repo_revision": "production",
02:35:27 INFO - "revision": "dde44491690467f0b59a942e5565ba5d3eed69de",
02:35:27 INFO - "slavename": "talos-linux64-ix-026",
02:35:27 INFO - "repo_path": "mozilla-central"
02:35:27 INFO - }
02:35:27 INFO - Finding installer, test and symbols from parent task.
02:35:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VAS2L2K7Qja5Jo8FIPfjJA'}, attempt #1
02:35:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VAS2L2K7Qja5Jo8FIPfjJA'}, attempt #1
02:35:28 INFO - Task dependencies: diuBdHD5SYu2aC0-wpyYfg
02:35:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg'}, attempt #1
02:35:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2
02:35:29 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.test_packages.json
02:35:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.crashreporter-symbols.zip
02:35:29 INFO - Running post-action listener: _resource_record_post_action
02:35:29 INFO - [mozharness: 2017-10-07 09:35:29.215493Z] Finished read-buildbot-config step (success)
02:35:29 INFO - [mozharness: 2017-10-07 09:35:29.215713Z] Running download-and-extract step.
02:35:29 INFO - Running pre-action listener: _resource_record_pre_action
02:35:29 INFO - Running main action method: download_and_extract
02:35:29 INFO - mkdir: /builds/slave/test/build/tests
02:35:29 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')]}
02:35:29 INFO - trying https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.test_packages.json
02:35:29 INFO - Downloading https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
02:35:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
02:35:29 INFO - Downloaded 989 bytes.
02:35:29 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
02:35:29 INFO - Using the following test package requirements:
02:35:29 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
02:35:29 INFO - u'common': [u'target.common.tests.zip'],
02:35:29 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
02:35:29 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
02:35:29 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
02:35:29 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
02:35:29 INFO - u'mozbase': [u'target.common.tests.zip'],
02:35:29 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
02:35:29 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
02:35:29 INFO - u'web-platform': [u'target.common.tests.zip',
02:35:29 INFO - u'target.web-platform.tests.tar.gz'],
02:35:29 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
02:35:29 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
02:35:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.common.tests.zip
02:35:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.common.tests.zip'}, attempt #1
02:35:29 INFO - Fetch https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.common.tests.zip into memory
02:35:32 INFO - Content-Length response header: 55318251
02:35:32 INFO - Bytes received: 55318251
02:35:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.talos.tests.zip
02:35:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.talos.tests.zip'}, attempt #1
02:35:43 INFO - Fetch https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.talos.tests.zip into memory
02:35:44 INFO - Content-Length response header: 14430198
02:35:44 INFO - Bytes received: 14430198
02:35:45 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')]}
02:35:45 INFO - trying https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2
02:35:45 INFO - Downloading https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
02:35:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
02:35:47 INFO - Downloaded 61959256 bytes.
02:35:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2
02:35:47 INFO - mkdir: /builds/slave/test/properties
02:35:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
02:35:47 INFO - Writing to file /builds/slave/test/properties/build_url
02:35:47 INFO - Contents:
02:35:47 INFO - build_url:https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2
02:35:47 INFO - Running post-action listener: _resource_record_post_action
02:35:47 INFO - Running post-action listener: find_tests_for_verification
02:35:47 INFO - Running post-action listener: set_extra_try_arguments
02:35:47 INFO - [mozharness: 2017-10-07 09:35:47.949046Z] Finished download-and-extract step (success)
02:35:47 INFO - [mozharness: 2017-10-07 09:35:47.949176Z] Running populate-webroot step.
02:35:47 INFO - Running pre-action listener: _resource_record_pre_action
02:35:47 INFO - Running main action method: populate_webroot
02:35:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
02:35:48 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['tresize',
02:35:48 INFO - 'tcanvasmark']},
02:35:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
02:35:48 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['dromaeo_css',
02:35:48 INFO - 'kraken']},
02:35:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:35:48 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:35:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'tests': ['damp', 'tps']},
02:35:48 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['damp', 'tps']},
02:35:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
02:35:48 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['dromaeo_dom']},
02:35:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
02:35:48 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['basic_compositor_video',
02:35:48 INFO - 'glvideo']},
02:35:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
02:35:48 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['ts_paint_webext',
02:35:48 INFO - 'tp5o_webext']},
02:35:48 INFO - 'other-e10s': {'tests': ['a11yr',
02:35:48 INFO - 'ts_paint',
02:35:48 INFO - 'tpaint',
02:35:48 INFO - 'sessionrestore',
02:35:48 INFO - 'sessionrestore_many_windows',
02:35:48 INFO - 'sessionrestore_no_auto_restore',
02:35:48 INFO - 'tabpaint',
02:35:48 INFO - 'cpstartup']},
02:35:48 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['a11yr',
02:35:48 INFO - 'ts_paint',
02:35:48 INFO - 'tpaint',
02:35:48 INFO - 'sessionrestore',
02:35:48 INFO - 'sessionrestore_many_windows',
02:35:48 INFO - 'sessionrestore_no_auto_restore',
02:35:48 INFO - 'tabpaint',
02:35:48 INFO - 'cpstartup']},
02:35:48 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
02:35:48 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
02:35:48 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['perf_reftest_singletons']},
02:35:48 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['perf_reftest']},
02:35:48 INFO - 'svgr-e10s': {'tests': ['tsvgx',
02:35:48 INFO - 'tsvgr_opacity',
02:35:48 INFO - 'tart',
02:35:48 INFO - 'tscrollx',
02:35:48 INFO - 'tsvg_static']},
02:35:48 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['tsvgx',
02:35:48 INFO - 'tsvgr_opacity',
02:35:48 INFO - 'tart',
02:35:48 INFO - 'tscrollx',
02:35:48 INFO - 'tsvg_static']},
02:35:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
02:35:48 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'talos_options': ['--disable-stylo'],
02:35:48 INFO - 'tests': ['tp5o']},
02:35:48 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:35:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:35:48 INFO - 'talos_options': ['--mitmproxy',
02:35:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:35:48 INFO - '--firstNonBlankPaint'],
02:35:48 INFO - 'tests': ['tp6_google',
02:35:48 INFO - 'tp6_youtube',
02:35:48 INFO - 'tp6_amazon',
02:35:48 INFO - 'tp6_facebook']},
02:35:48 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:35:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:35:48 INFO - 'talos_options': ['--disable-stylo',
02:35:48 INFO - '--mitmproxy',
02:35:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:35:48 INFO - '--firstNonBlankPaint'],
02:35:48 INFO - 'tests': ['tp6_google',
02:35:48 INFO - 'tp6_youtube',
02:35:48 INFO - 'tp6_amazon',
02:35:48 INFO - 'tp6_facebook']},
02:35:48 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:35:48 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:35:48 INFO - 'talos_options': ['--stylo-threads=1',
02:35:48 INFO - '--mitmproxy',
02:35:48 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:35:48 INFO - '--firstNonBlankPaint'],
02:35:48 INFO - 'tests': ['tp6_google',
02:35:48 INFO - 'tp6_youtube',
02:35:48 INFO - 'tp6_amazon',
02:35:48 INFO - 'tp6_facebook']},
02:35:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'talos_options': ['--xperf_path',
02:35:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:35:48 INFO - 'tests': ['tp5n']},
02:35:48 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:35:48 INFO - 'talos_options': ['--disable-stylo',
02:35:48 INFO - '--xperf_path',
02:35:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:35:48 INFO - 'tests': ['tp5n']}}}
02:35:48 INFO - Running post-action listener: _resource_record_post_action
02:35:48 INFO - [mozharness: 2017-10-07 09:35:48.235700Z] Finished populate-webroot step (success)
02:35:48 INFO - [mozharness: 2017-10-07 09:35:48.235924Z] Running create-virtualenv step.
02:35:48 INFO - Running pre-action listener: _resource_record_pre_action
02:35:48 INFO - Running main action method: create_virtualenv
02:35:48 INFO - Creating virtualenv /builds/slave/test/build/venv
02:35:48 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build
02:35:48 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv
02:35:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
02:35:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior.
02:35:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python
02:35:48 INFO - Installing distribute.............................................................................................................................................................................................done.
02:35:49 INFO - Installing pip...............done.
02:35:49 INFO - Return code: 0
02:35:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version']
02:35:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version
02:35:49 INFO - Reading from file tmpfile_stdout
02:35:49 INFO - Output received:
02:35:49 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7)
02:35:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
02:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:35:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:35:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:35:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:35:49 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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:35:49 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', 'pip>=1.5'] in /builds/slave/test/build
02:35:49 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 pip>=1.5
02:35:49 INFO - Using env: {'DISPLAY': ':0',
02:35:49 INFO - 'HOME': '/home/cltbld',
02:35:49 INFO - 'LANG': 'en_US.UTF-8',
02:35:49 INFO - 'LANGUAGE': 'en_US:en',
02:35:49 INFO - 'LOGNAME': 'cltbld',
02:35:49 INFO - 'MAIL': '/var/mail/cltbld',
02:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:35:49 INFO - 'MOZ_NO_REMOTE': '1',
02:35:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:35:49 INFO - 'NO_EM_RESTART': '1',
02:35:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:35:49 INFO - 'PWD': '/builds/slave/test',
02:35:49 INFO - 'SHELL': '/bin/bash',
02:35:49 INFO - 'SHLVL': '1',
02:35:49 INFO - 'TERM': 'linux',
02:35:49 INFO - 'TMOUT': '86400',
02:35:49 INFO - 'USER': 'cltbld',
02:35:49 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:35:49 INFO - '_': '/tools/buildbot/bin/python'}
02:35:50 INFO - Ignoring indexes: http://pypi.python.org/simple/
02:35:50 INFO - Downloading/unpacking pip>=1.5
02:35:50 INFO - Running setup.py egg_info for package pip
02:35:50 INFO - warning: no files found matching 'pip/cacert.pem'
02:35:50 INFO - warning: no files found matching '*.html' under directory 'docs'
02:35:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build'
02:35:50 INFO - no previously-included directories found matching 'docs/_build/_sources'
02:35:50 INFO - Installing collected packages: pip
02:35:50 INFO - Found existing installation: pip 1.1
02:35:50 INFO - Uninstalling pip:
02:35:50 INFO - Successfully uninstalled pip
02:35:50 INFO - Running setup.py install for pip
02:35:50 INFO - warning: no files found matching 'pip/cacert.pem'
02:35:50 INFO - warning: no files found matching '*.html' under directory 'docs'
02:35:50 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build'
02:35:50 INFO - no previously-included directories found matching 'docs/_build/_sources'
02:35:50 INFO - Installing pip script to /builds/slave/test/build/venv/bin
02:35:50 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin
02:35:50 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin
02:35:50 INFO - Successfully installed pip
02:35:50 INFO - Cleaning up...
02:35:50 INFO - Return code: 0
02:35:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
02:35:50 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')]}
02:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:35:50 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')]}
02:35:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:35:50 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', '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 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:35:50 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', 'psutil>=3.1.1'] in /builds/slave/test/build
02:35:50 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 psutil>=3.1.1
02:35:50 INFO - Using env: {'DISPLAY': ':0',
02:35:50 INFO - 'HOME': '/home/cltbld',
02:35:50 INFO - 'LANG': 'en_US.UTF-8',
02:35:50 INFO - 'LANGUAGE': 'en_US:en',
02:35:50 INFO - 'LOGNAME': 'cltbld',
02:35:50 INFO - 'MAIL': '/var/mail/cltbld',
02:35:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:35:50 INFO - 'MOZ_NO_REMOTE': '1',
02:35:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:35:50 INFO - 'NO_EM_RESTART': '1',
02:35:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:35:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:35:50 INFO - 'PWD': '/builds/slave/test',
02:35:50 INFO - 'SHELL': '/bin/bash',
02:35:50 INFO - 'SHLVL': '1',
02:35:50 INFO - 'TERM': 'linux',
02:35:50 INFO - 'TMOUT': '86400',
02:35:50 INFO - 'USER': 'cltbld',
02:35:50 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:35:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:35:50 INFO - '_': '/tools/buildbot/bin/python'}
02:35:50 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:35:50 INFO - Downloading/unpacking psutil>=3.1.1
02:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil
02:35:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
02:35:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects
...
02:35:51 INFO - Installing collected packages: psutil
02:35:51 INFO - Running setup.py install for psutil
02:35:51 INFO - building 'psutil._psutil_linux' extension
02:35:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o
02:35:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so
02:35:52 INFO - building 'psutil._psutil_posix' extension
02:35:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o
02:35:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so
02:35:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build'
02:35:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ...
02:35:52 INFO - Successfully installed psutil
02:35:52 INFO - Cleaning up...
02:35:52 INFO - Return code: 0
02:35:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
02:35: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')]}
02:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:35: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')]}
02:35:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:35: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:35: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
02:35: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 mozsystemmonitor==0.3
02:35:52 INFO - Using env: {'DISPLAY': ':0',
02:35:52 INFO - 'HOME': '/home/cltbld',
02:35:52 INFO - 'LANG': 'en_US.UTF-8',
02:35:52 INFO - 'LANGUAGE': 'en_US:en',
02:35:52 INFO - 'LOGNAME': 'cltbld',
02:35:52 INFO - 'MAIL': '/var/mail/cltbld',
02:35:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:35:52 INFO - 'MOZ_NO_REMOTE': '1',
02:35:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:35:52 INFO - 'NO_EM_RESTART': '1',
02:35:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:35:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:35:52 INFO - 'PWD': '/builds/slave/test',
02:35:52 INFO - 'SHELL': '/bin/bash',
02:35:52 INFO - 'SHLVL': '1',
02:35:52 INFO - 'TERM': 'linux',
02:35:52 INFO - 'TMOUT': '86400',
02:35:52 INFO - 'USER': 'cltbld',
02:35:52 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:35:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:35:52 INFO - '_': '/tools/buildbot/bin/python'}
02:35:52 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:35:52 INFO - Downloading/unpacking mozsystemmonitor==0.3
02:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz
02:35:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor
02:35:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
02:35:53 INFO - Installing collected packages: mozsystemmonitor
02:35:53 INFO - Running setup.py install for mozsystemmonitor
02:35:53 INFO - Successfully installed mozsystemmonitor
02:35:53 INFO - Cleaning up...
02:35:54 INFO - Return code: 0
02:35:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
02:35:54 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')]}
02:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:35:54 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')]}
02:35:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:35:54 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', '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 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:35:54 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', 'jsonschema==2.5.1'] in /builds/slave/test/build
02:35:54 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 jsonschema==2.5.1
02:35:54 INFO - Using env: {'DISPLAY': ':0',
02:35:54 INFO - 'HOME': '/home/cltbld',
02:35:54 INFO - 'LANG': 'en_US.UTF-8',
02:35:54 INFO - 'LANGUAGE': 'en_US:en',
02:35:54 INFO - 'LOGNAME': 'cltbld',
02:35:54 INFO - 'MAIL': '/var/mail/cltbld',
02:35:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:35:54 INFO - 'MOZ_NO_REMOTE': '1',
02:35:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:35:54 INFO - 'NO_EM_RESTART': '1',
02:35:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:35:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:35:54 INFO - 'PWD': '/builds/slave/test',
02:35:54 INFO - 'SHELL': '/bin/bash',
02:35:54 INFO - 'SHLVL': '1',
02:35:54 INFO - 'TERM': 'linux',
02:35:54 INFO - 'TMOUT': '86400',
02:35:54 INFO - 'USER': 'cltbld',
02:35:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:35:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:35:54 INFO - '_': '/tools/buildbot/bin/python'}
02:35:54 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:35:54 INFO - Downloading/unpacking jsonschema==2.5.1
02:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl
02:35:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1)
02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:56 INFO - Downloading functools32-3.2.3-2.tar.gz
02:35:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32
02:35:56 INFO - warning: no files found matching '*.txt'
02:35:56 INFO - no previously-included directories found matching 'build'
02:35:56 INFO - no previously-included directories found matching 'dist'
02:35:56 INFO - no previously-included directories found matching '.git*'
02:35:56 INFO - Installing collected packages: jsonschema, functools32
02:35:56 INFO - Running setup.py install for functools32
02:35:56 INFO - warning: no files found matching '*.txt'
02:35:56 INFO - no previously-included directories found matching 'build'
02:35:56 INFO - no previously-included directories found matching 'dist'
02:35:56 INFO - no previously-included directories found matching '.git*'
02:35:56 INFO - Successfully installed jsonschema functools32
02:35:56 INFO - Cleaning up...
02:35:56 INFO - Return code: 0
02:35:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
02:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:35:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:35:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build
02:35:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2
02:35:56 INFO - Using env: {'DISPLAY': ':0',
02:35:56 INFO - 'HOME': '/home/cltbld',
02:35:56 INFO - 'LANG': 'en_US.UTF-8',
02:35:56 INFO - 'LANGUAGE': 'en_US:en',
02:35:56 INFO - 'LOGNAME': 'cltbld',
02:35:56 INFO - 'MAIL': '/var/mail/cltbld',
02:35:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:35:56 INFO - 'MOZ_NO_REMOTE': '1',
02:35:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:35:56 INFO - 'NO_EM_RESTART': '1',
02:35:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:35:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:35:56 INFO - 'PWD': '/builds/slave/test',
02:35:56 INFO - 'SHELL': '/bin/bash',
02:35:56 INFO - 'SHLVL': '1',
02:35:56 INFO - 'TERM': 'linux',
02:35:56 INFO - 'TMOUT': '86400',
02:35:56 INFO - 'USER': 'cltbld',
02:35:56 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:35:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:35:56 INFO - '_': '/tools/buildbot/bin/python'}
02:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:35:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
02:35:56 INFO - Cleaning up...
02:35:56 INFO - Return code: 0
02:35:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
02:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:35:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:35:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:35:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build
02:35:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
02:35:56 INFO - Using env: {'DISPLAY': ':0',
02:35:56 INFO - 'HOME': '/home/cltbld',
02:35:56 INFO - 'LANG': 'en_US.UTF-8',
02:35:56 INFO - 'LANGUAGE': 'en_US:en',
02:35:56 INFO - 'LOGNAME': 'cltbld',
02:35:56 INFO - 'MAIL': '/var/mail/cltbld',
02:35:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:35:56 INFO - 'MOZ_NO_REMOTE': '1',
02:35:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:35:56 INFO - 'NO_EM_RESTART': '1',
02:35:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:35:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:35:56 INFO - 'PWD': '/builds/slave/test',
02:35:56 INFO - 'SHELL': '/bin/bash',
02:35:56 INFO - 'SHLVL': '1',
02:35:56 INFO - 'TERM': 'linux',
02:35:56 INFO - 'TMOUT': '86400',
02:35:56 INFO - 'USER': 'cltbld',
02:35:56 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:35:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:35:56 INFO - '_': '/tools/buildbot/bin/python'}
02:35:56 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:35:56 INFO - Downloading/unpacking blobuploader==1.2.4
02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:58 INFO - Downloading blobuploader-1.2.4.tar.gz
02:35:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
02:35:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
02:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:35:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:35:58 INFO - HTTP error 403 while getting http://pypi.pvt.build.mozilla.org/pub/requests-1.2.3.tar.gz (from http://pypi.pvt.build.mozilla.org/pub/)
02:35:58 INFO - Cleaning up...
02:35:58 INFO - Exception:
02:35:58 ERROR - Traceback (most recent call last):
02:35:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/basecommand.py", line 122, in main
02:35:58 INFO - status = self.run(options, args)
02:35:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/commands/install.py", line 278, in run
02:35:58 INFO - requirement_set.prepare_files(finder, force_root_egg_info=self.bundle, bundle=self.bundle)
02:35:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1197, in prepare_files
02:35:58 INFO - do_download,
02:35:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/req.py", line 1375, in unpack_url
02:35:58 INFO - self.session,
02:35:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/download.py", line 547, in unpack_http_url
02:35:58 INFO - resp.raise_for_status()
02:35:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip/_vendor/requests/models.py", line 773, in raise_for_status
02:35:58 INFO - raise HTTPError(http_error_msg, response=self)
02:35:58 INFO - HTTPError: 403 Client Error: Forbidden
02:35:58 INFO - Storing debug log for failure in /home/cltbld/.pip/pip.log
02:35:58 WARNING - Return code: 2
02:35:58 INFO - retry: Failed, sleeping 60 seconds before retrying
02:36:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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 #2
02:36:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build
02:36:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4
02:36:58 INFO - Using env: {'DISPLAY': ':0',
02:36:58 INFO - 'HOME': '/home/cltbld',
02:36:58 INFO - 'LANG': 'en_US.UTF-8',
02:36:58 INFO - 'LANGUAGE': 'en_US:en',
02:36:58 INFO - 'LOGNAME': 'cltbld',
02:36:58 INFO - 'MAIL': '/var/mail/cltbld',
02:36:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:58 INFO - 'MOZ_NO_REMOTE': '1',
02:36:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:58 INFO - 'NO_EM_RESTART': '1',
02:36:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:58 INFO - 'PWD': '/builds/slave/test',
02:36:58 INFO - 'SHELL': '/bin/bash',
02:36:58 INFO - 'SHLVL': '1',
02:36:58 INFO - 'TERM': 'linux',
02:36:58 INFO - 'TMOUT': '86400',
02:36:58 INFO - 'USER': 'cltbld',
02:36:58 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:36:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:58 INFO - '_': '/tools/buildbot/bin/python'}
02:36:58 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:36:58 INFO - Downloading/unpacking blobuploader==1.2.4
02:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:36:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:36:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:36:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:36:59 INFO - Downloading blobuploader-1.2.4.tar.gz
02:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader
02:36:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4)
02:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
02:37:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4)
02:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:00 INFO - Downloading docopt-0.6.1.tar.gz
02:37:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt
02:37:00 INFO - Installing collected packages: blobuploader, requests, docopt
02:37:00 INFO - Running setup.py install for blobuploader
02:37:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775
02:37:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775
02:37:00 INFO - Running setup.py install for requests
02:37:00 INFO - Running setup.py install for docopt
02:37:00 INFO - Successfully installed blobuploader requests docopt
02:37:00 INFO - Cleaning up...
02:37:00 INFO - Return code: 0
02:37:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:37:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:37:00 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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:37:00 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'] in /builds/slave/test/build/tests/config
02:37:00 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
02:37:00 INFO - Using env: {'DISPLAY': ':0',
02:37:00 INFO - 'HOME': '/home/cltbld',
02:37:00 INFO - 'LANG': 'en_US.UTF-8',
02:37:00 INFO - 'LANGUAGE': 'en_US:en',
02:37:00 INFO - 'LOGNAME': 'cltbld',
02:37:00 INFO - 'MAIL': '/var/mail/cltbld',
02:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:00 INFO - 'MOZ_NO_REMOTE': '1',
02:37:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:37:00 INFO - 'NO_EM_RESTART': '1',
02:37:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:37:00 INFO - 'PWD': '/builds/slave/test',
02:37:00 INFO - 'SHELL': '/bin/bash',
02:37:00 INFO - 'SHLVL': '1',
02:37:00 INFO - 'TERM': 'linux',
02:37:00 INFO - 'TMOUT': '86400',
02:37:00 INFO - 'USER': 'cltbld',
02:37:00 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:00 INFO - '_': '/tools/buildbot/bin/python'}
02:37:01 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
02:37:01 INFO - Running setup.py (path:/tmp/pip-aSyPE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
02:37:01 INFO - Running setup.py (path:/tmp/pip-N8HLYc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
02:37:01 INFO - Running setup.py (path:/tmp/pip-I1vt0v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
02:37:01 INFO - Running setup.py (path:/tmp/pip-XFiAtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
02:37:01 INFO - Running setup.py (path:/tmp/pip-zqZk1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
02:37:01 INFO - Running setup.py (path:/tmp/pip-gKgzTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
02:37:01 INFO - Running setup.py (path:/tmp/pip-zHyKZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
02:37:01 INFO - Running setup.py (path:/tmp/pip-1h6sQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
02:37:01 INFO - Running setup.py (path:/tmp/pip-p7mnCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
02:37:01 INFO - Running setup.py (path:/tmp/pip-6FOnOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
02:37:01 INFO - Running setup.py (path:/tmp/pip-AeUAR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
02:37:01 INFO - Running setup.py (path:/tmp/pip-q90PEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
02:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
02:37:01 INFO - Running setup.py (path:/tmp/pip-838U1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
02:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
02:37:02 INFO - Running setup.py (path:/tmp/pip-Y2ZFDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
02:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
02:37:02 INFO - Running setup.py (path:/tmp/pip-s5WPC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
02:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
02:37:02 INFO - Running setup.py (path:/tmp/pip-FhfdW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
02:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
02:37:02 INFO - Running setup.py (path:/tmp/pip-GZpXVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
02:37:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:37:02 INFO - Running setup.py install for manifestparser
02:37:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin
02:37:02 INFO - Running setup.py install for mozcrash
02:37:02 INFO - Running setup.py install for mozdebug
02:37:02 INFO - Running setup.py install for mozdevice
02:37:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin
02:37:02 INFO - Running setup.py install for mozfile
02:37:02 INFO - Running setup.py install for mozhttpd
02:37:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin
02:37:02 INFO - Running setup.py install for mozinfo
02:37:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin
02:37:02 INFO - Running setup.py install for mozInstall
02:37:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Running setup.py install for mozleak
02:37:03 INFO - Running setup.py install for mozlog
02:37:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Running setup.py install for moznetwork
02:37:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Running setup.py install for mozprocess
02:37:03 INFO - Running setup.py install for mozprofile
02:37:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Running setup.py install for mozrunner
02:37:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin
02:37:03 INFO - Running setup.py install for mozscreenshot
02:37:03 INFO - Running setup.py install for moztest
02:37:03 INFO - Running setup.py install for mozversion
02:37:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin
02:37:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion
02:37:04 INFO - Cleaning up...
02:37:04 INFO - Return code: 0
02:37:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:37:04 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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:37:04 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'] in /builds/slave/test/build/tests/config
02:37:04 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
02:37:04 INFO - Using env: {'DISPLAY': ':0',
02:37:04 INFO - 'HOME': '/home/cltbld',
02:37:04 INFO - 'LANG': 'en_US.UTF-8',
02:37:04 INFO - 'LANGUAGE': 'en_US:en',
02:37:04 INFO - 'LOGNAME': 'cltbld',
02:37:04 INFO - 'MAIL': '/var/mail/cltbld',
02:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:04 INFO - 'MOZ_NO_REMOTE': '1',
02:37:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:37:04 INFO - 'NO_EM_RESTART': '1',
02:37:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:37:04 INFO - 'PWD': '/builds/slave/test',
02:37:04 INFO - 'SHELL': '/bin/bash',
02:37:04 INFO - 'SHLVL': '1',
02:37:04 INFO - 'TERM': 'linux',
02:37:04 INFO - 'TMOUT': '86400',
02:37:04 INFO - 'USER': 'cltbld',
02:37:04 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:04 INFO - '_': '/tools/buildbot/bin/python'}
02:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser
02:37:04 INFO - Running setup.py (path:/tmp/pip-WUmkA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash
02:37:04 INFO - Running setup.py (path:/tmp/pip-dIypji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug
02:37:04 INFO - Running setup.py (path:/tmp/pip-vGUDMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice
02:37:04 INFO - Running setup.py (path:/tmp/pip-tFWt17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile
02:37:04 INFO - Running setup.py (path:/tmp/pip-i2tDNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd
02:37:04 INFO - Running setup.py (path:/tmp/pip-oKbLJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo
02:37:04 INFO - Running setup.py (path:/tmp/pip-DoUyMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall
02:37:04 INFO - Running setup.py (path:/tmp/pip-qlgCa9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak
02:37:04 INFO - Running setup.py (path:/tmp/pip-YRY3r1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak
02:37:04 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))
02:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog
02:37:04 INFO - Running setup.py (path:/tmp/pip-zK8kc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog
02:37:05 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))
02:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork
02:37:05 INFO - Running setup.py (path:/tmp/pip-XLQZ7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork
02:37:05 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))
02:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess
02:37:05 INFO - Running setup.py (path:/tmp/pip-Io6M3_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess
02:37:05 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))
02:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile
02:37:05 INFO - Running setup.py (path:/tmp/pip-xCIMie-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile
02:37:05 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))
02:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner
02:37:05 INFO - Running setup.py (path:/tmp/pip-FlDi8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner
02:37:05 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))
02:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot
02:37:05 INFO - Running setup.py (path:/tmp/pip-VRpP68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot
02:37:05 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))
02:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest
02:37:05 INFO - Running setup.py (path:/tmp/pip-v1JgQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest
02:37:05 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))
02:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion
02:37:05 INFO - Running setup.py (path:/tmp/pip-BuuCpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion
02:37:05 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))
02:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
02:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:37:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:37:05 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))
02:37:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:06 INFO - Downloading blessings-1.6.tar.gz
02:37:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings
02:37:06 INFO - Installing collected packages: blessings
02:37:06 INFO - Running setup.py install for blessings
02:37:06 INFO - Successfully installed blessings
02:37:06 INFO - Cleaning up...
02:37:06 INFO - Return code: 0
02:37:06 INFO - Done creating virtualenv /builds/slave/test/build/venv.
02:37:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:37:07 INFO - Reading from file tmpfile_stdout
02:37:07 INFO - Current package versions:
02:37:07 INFO - argparse == 1.2.1
02:37:07 INFO - blessings == 1.6
02:37:07 INFO - blobuploader == 1.2.4
02:37:07 INFO - docopt == 0.6.1
02:37:07 INFO - functools32 == 3.2.3-2
02:37:07 INFO - jsonschema == 2.5.1
02:37:07 INFO - manifestparser == 1.1
02:37:07 INFO - mozInstall == 1.13
02:37:07 INFO - mozcrash == 1.0
02:37:07 INFO - mozdebug == 0.1
02:37:07 INFO - mozdevice == 0.51
02:37:07 INFO - mozfile == 1.2
02:37:07 INFO - mozhttpd == 0.7
02:37:07 INFO - mozinfo == 0.10
02:37:07 INFO - mozleak == 0.1
02:37:07 INFO - mozlog == 3.5
02:37:07 INFO - moznetwork == 0.27
02:37:07 INFO - mozprocess == 0.25
02:37:07 INFO - mozprofile == 0.28
02:37:07 INFO - mozrunner == 6.13
02:37:07 INFO - mozscreenshot == 0.1
02:37:07 INFO - mozsystemmonitor == 0.3
02:37:07 INFO - moztest == 0.8
02:37:07 INFO - mozversion == 1.4
02:37:07 INFO - psutil == 3.1.1
02:37:07 INFO - requests == 1.2.3
02:37:07 INFO - wsgiref == 0.1.2
02:37:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:37: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')]}
02:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:37: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')]}
02:37:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:37:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:37:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos
02:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub
02:37:07 INFO - Using env: {'DISPLAY': ':0',
02:37:07 INFO - 'HOME': '/home/cltbld',
02:37:07 INFO - 'LANG': 'en_US.UTF-8',
02:37:07 INFO - 'LANGUAGE': 'en_US:en',
02:37:07 INFO - 'LOGNAME': 'cltbld',
02:37:07 INFO - 'MAIL': '/var/mail/cltbld',
02:37:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:07 INFO - 'MOZ_NO_REMOTE': '1',
02:37:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:37:07 INFO - 'NO_EM_RESTART': '1',
02:37:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:37:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:37:07 INFO - 'PWD': '/builds/slave/test',
02:37:07 INFO - 'SHELL': '/bin/bash',
02:37:07 INFO - 'SHLVL': '1',
02:37:07 INFO - 'TERM': 'linux',
02:37:07 INFO - 'TMOUT': '86400',
02:37:07 INFO - 'USER': 'cltbld',
02:37:07 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:37:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:07 INFO - '_': '/tools/buildbot/bin/python'}
02:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
02:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
02:37:07 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
02:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson
02:37:08 INFO - Downloading/unpacking requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available
02:37:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available
02:37:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests
02:37:08 INFO - warning: no files found matching 'test_requests.py'
02:37:08 INFO - warning: no files found matching 'requirements.txt'
02:37:08 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))
02:37:08 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))
02:37:08 INFO - Installing collected packages: simplejson, requests
02:37:08 INFO - Running setup.py install for simplejson
02:37:08 INFO - building 'simplejson._speedups' extension
02:37:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o
02:37:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so
02:37:09 INFO - Found existing installation: requests 1.2.3
02:37:09 INFO - Uninstalling requests:
02:37:09 INFO - Successfully uninstalled requests
02:37:09 INFO - Running setup.py install for requests
02:37:09 INFO - warning: no files found matching 'test_requests.py'
02:37:09 INFO - warning: no files found matching 'requirements.txt'
02:37:10 INFO - Successfully installed simplejson requests
02:37:10 INFO - Cleaning up...
02:37:10 INFO - Return code: 0
02:37:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
02:37:10 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')]}
02:37:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:37:10 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')]}
02:37:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:37:10 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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc5d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe25b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xfca9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351', '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
02:37:10 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', 'jsonschema'] in /builds/slave/test/build
02:37:10 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 jsonschema
02:37:10 INFO - Using env: {'DISPLAY': ':0',
02:37:10 INFO - 'HOME': '/home/cltbld',
02:37:10 INFO - 'LANG': 'en_US.UTF-8',
02:37:10 INFO - 'LANGUAGE': 'en_US:en',
02:37:10 INFO - 'LOGNAME': 'cltbld',
02:37:10 INFO - 'MAIL': '/var/mail/cltbld',
02:37:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:10 INFO - 'MOZ_NO_REMOTE': '1',
02:37:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:37:10 INFO - 'NO_EM_RESTART': '1',
02:37:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:37:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:37:10 INFO - 'PWD': '/builds/slave/test',
02:37:10 INFO - 'SHELL': '/bin/bash',
02:37:10 INFO - 'SHLVL': '1',
02:37:10 INFO - 'TERM': 'linux',
02:37:10 INFO - 'TMOUT': '86400',
02:37:10 INFO - 'USER': 'cltbld',
02:37:10 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:37:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:10 INFO - '_': '/tools/buildbot/bin/python'}
02:37:10 INFO - Ignoring indexes: https://pypi.python.org/simple/
02:37:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
02:37:10 INFO - Cleaning up...
02:37:10 INFO - Return code: 0
02:37:10 INFO - Running post-action listener: _resource_record_post_action
02:37:10 INFO - Running post-action listener: _start_resource_monitoring
02:37:10 INFO - Starting resource monitoring.
02:37:10 INFO - [mozharness: 2017-10-07 09:37:10.569258Z] Finished create-virtualenv step (success)
02:37:10 INFO - [mozharness: 2017-10-07 09:37:10.569770Z] Running install step.
02:37:10 INFO - Running pre-action listener: _resource_record_pre_action
02:37:10 INFO - Running main action method: install
02:37:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:37:10 INFO - Reading from file tmpfile_stdout
02:37:10 INFO - Detecting whether we're running mozinstall >=1.0...
02:37:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
02:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
02:37:10 INFO - Reading from file tmpfile_stdout
02:37:10 INFO - Output received:
02:37:10 INFO - Usage: mozinstall [options] installer
02:37:10 INFO - Options:
02:37:10 INFO - -h, --help show this help message and exit
02:37:10 INFO - -d DEST, --destination=DEST
02:37:10 INFO - Directory to install application into. [default:
02:37:10 INFO - "/builds/slave/test"]
02:37:10 INFO - --app=APP Application being installed. [default: firefox]
02:37:10 INFO - mkdir: /builds/slave/test/build/application
02:37:10 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']
02:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
02:37:28 INFO - Reading from file tmpfile_stdout
02:37:28 INFO - Output received:
02:37:28 INFO - /builds/slave/test/build/application/firefox/firefox
02:37:28 INFO - Running post-action listener: _resource_record_post_action
02:37:28 INFO - [mozharness: 2017-10-07 09:37:28.830452Z] Finished install step (success)
02:37:28 INFO - [mozharness: 2017-10-07 09:37:28.830702Z] Running setup-mitmproxy step.
02:37:28 INFO - Running pre-action listener: _resource_record_pre_action
02:37:28 INFO - Running main action method: setup_mitmproxy
02:37:28 INFO - Skipping: mitmproxy is not required
02:37:28 INFO - Running post-action listener: _resource_record_post_action
02:37:28 INFO - [mozharness: 2017-10-07 09:37:28.831819Z] Finished setup-mitmproxy step (success)
02:37:28 INFO - [mozharness: 2017-10-07 09:37:28.832037Z] Running run-tests step.
02:37:28 INFO - Running pre-action listener: _resource_record_pre_action
02:37:28 INFO - Running pre-action listener: _set_gcov_prefix
02:37:28 INFO - Running main action method: run_tests
02:37:28 WARNING - Try message not found.
02:37:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
02:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
02:37:28 INFO - Python 2.7.3
02:37:28 INFO - Return code: 0
02:37:28 INFO - grabbing minidump binary from tooltool
02:37:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:37:28 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 0xfc7d60>, '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 0xfc8a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfc8ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
02:37:28 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
02:37:28 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
02:37:28 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
02:37:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
02:37:28 INFO - Return code: 0
02:37:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
02:37:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
02:37:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
02:37:28 INFO - ENV: RUST_BACKTRACE is now full
02:37:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
02:37:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
02:37:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build
02:37:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-026 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log
02:37:28 INFO - Using env: {'DISPLAY': ':0',
02:37:28 INFO - 'HOME': '/home/cltbld',
02:37:28 INFO - 'LANG': 'en_US.UTF-8',
02:37:28 INFO - 'LANGUAGE': 'en_US:en',
02:37:28 INFO - 'LOGNAME': 'cltbld',
02:37:28 INFO - 'MAIL': '/var/mail/cltbld',
02:37:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:37:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:37:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:28 INFO - 'MOZ_NO_REMOTE': '1',
02:37:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:37:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:37:28 INFO - 'NO_EM_RESTART': '1',
02:37:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:37:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:37:28 INFO - 'PWD': '/builds/slave/test',
02:37:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:37:28 INFO - 'RUST_BACKTRACE': 'full',
02:37:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:37:28 INFO - 'SHELL': '/bin/bash',
02:37:28 INFO - 'SHLVL': '1',
02:37:28 INFO - 'TERM': 'linux',
02:37:28 INFO - 'TMOUT': '86400',
02:37:28 INFO - 'USER': 'cltbld',
02:37:28 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:37:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:28 INFO - '_': '/tools/buildbot/bin/python'}
02:37:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] with output_timeout 3600
02:37:29 INFO - mozversion application_buildid: 20171007083845
02:37:29 INFO - mozversion application_changeset: dde44491690467f0b59a942e5565ba5d3eed69de
02:37:29 INFO - mozversion application_display_name: Nightly
02:37:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:37:29 INFO - mozversion application_name: Firefox
02:37:29 INFO - mozversion application_remotingname: firefox
02:37:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
02:37:29 INFO - mozversion application_vendor: Mozilla
02:37:29 INFO - mozversion application_version: 58.0a1
02:37:29 INFO - mozversion platform_buildid: 20171007083845
02:37:29 INFO - mozversion platform_changeset: dde44491690467f0b59a942e5565ba5d3eed69de
02:37:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
02:37:29 INFO - mozversion platform_version: 58.0a1
02:37:29 INFO - using testdate: 1507369049
02:37:29 INFO - actual date: 1507369049
02:37:29 INFO - starting webserver on 'localhost:51778'
02:37:29 INFO - SUITE-START | Running 2 tests
02:37:29 INFO - TEST-START | basic_compositor_video
02:37:29 INFO - Initialising browser for basic_compositor_video test...
02:37:29 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51778/getInfo.html -profile /tmp/tmpyqVQro/profile
02:37:29 INFO - TEST-INFO | started process 1166 (/builds/slave/test/build/application/firefox/firefox http://localhost:51778/getInfo.html)
02:37:34 INFO - TEST-INFO | 1166: exit 0
02:37:34 INFO - Browser initialized.
02:37:34 INFO - Running cycle 1/1 for basic_compositor_video test...
02:37:34 INFO - Using env: {'DISPLAY': ':0',
02:37:34 INFO - 'HOME': '/home/cltbld',
02:37:34 INFO - 'JSGC_DISABLE_POISONING': '1',
02:37:34 INFO - 'LANG': 'en_US.UTF-8',
02:37:34 INFO - 'LANGUAGE': 'en_US:en',
02:37:34 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:37:34 INFO - 'LOGNAME': 'cltbld',
02:37:34 INFO - 'MAIL': '/var/mail/cltbld',
02:37:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:37:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:37:34 INFO - 'MOZ_CRASHREPORTER': '1',
02:37:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:34 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:37:34 INFO - 'MOZ_NO_REMOTE': '1',
02:37:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:37:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:37:34 INFO - 'NO_EM_RESTART': '1',
02:37:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:37:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:37:34 INFO - 'PWD': '/builds/slave/test',
02:37:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:37:34 INFO - 'RUST_BACKTRACE': 'full',
02:37:34 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:37:34 INFO - 'SHELL': '/bin/bash',
02:37:34 INFO - 'SHLVL': '1',
02:37:34 INFO - 'STYLO_FORCE_DISABLED': '1',
02:37:34 INFO - 'TERM': 'linux',
02:37:34 INFO - 'TMOUT': '86400',
02:37:34 INFO - 'USER': 'cltbld',
02:37:34 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:37:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:34 INFO - '_': '/tools/buildbot/bin/python'}
02:37:34 INFO - TEST-INFO | started process 1494 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyqVQro/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
02:37:35 INFO - PID 1494 |
02:37:35 INFO - PID 1494 | (/builds/slave/test/build/application/firefox/firefox:1555): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:37:35 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6805291153415454 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6699457429048412 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7478013977868372 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7575659050966612 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093751705320599 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.05005060728745 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.5138583138173294 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.455742659758204 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.445862068965514 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5876675977653685 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.288931888544894 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.5277380952381 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.282575187969915 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.641303191489374 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296782786885249 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83434911242602 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.408365019011413 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.699197860962567 ms/frame
02:38:21 INFO - PID 1494 |
02:38:21 INFO - PID 1494 | Cycle 1(1): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:39:07 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682812675266405 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6712406015037593 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.779614472123369 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7396130434782604 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.049730094466937 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.073482688391039 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.469364161849711 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3732546374367627 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.545397412199633 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.682769886363634 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.837065573770493 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.90329207920792 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.365075757575747 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.112694444444465 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.448423236514522 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977095808383208 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.498103448275852 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87119565217393 ms/frame
02:39:07 INFO - PID 1494 |
02:39:07 INFO - PID 1494 | Cycle 1(2): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:39:53 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6883652222847496 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670083333333336 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.749326530612245 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7468602620087326 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.105075239398084 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.049605263157897 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.6023229291716694 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.565784313725488 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693548387096774 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.57139275766017 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 10.205850340136056 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.348200934579436 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.28082706766919 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.873451086956504 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.501166666666661 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.051867469879522 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.53874999999999 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.0507182320442 ms/frame
02:39:53 INFO - PID 1494 |
02:39:53 INFO - PID 1494 | Cycle 1(3): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:40:39 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686002808988764 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6726128762541808 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.759885630498534 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7259188955996543 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1214354395604405 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.177411273486429 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.477207415990728 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.436838487972511 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.770048076923075 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589413407821224 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 10.001066666666667 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.709174757281554 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.409866920152098 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.871983695652172 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39787190082646 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.837100591715982 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.367518939393944 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.871141304347791 ms/frame
02:40:39 INFO - PID 1494 |
02:40:39 INFO - PID 1494 | Cycle 1(4): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:41:25 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.686523327712198 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6716290726817042 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7546315789473685 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7291356957649089 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1678194444444445 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.125865979381444 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.615457831325301 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5530106571936075 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.639323308270682 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.749942528735631 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.5564331210191 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.049954751131226 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.364772727272724 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.93021857923499 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605147058823535 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.697251461988323 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.324301886792462 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.696524064171124 ms/frame
02:41:25 INFO - PID 1494 |
02:41:25 INFO - PID 1494 | Cycle 1(5): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:42:12 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6818974215246636 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6698080133555928 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7585873388042201 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7310164359861595 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.087813351498638 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.107864476386038 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.6236654589371966 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5721785714285748 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715409523809524 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.60393557422969 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.902326732673263 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.618245192307699 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.368541666666674 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.989752747252759 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.146012145748967 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.699678362573124 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.676906614786008 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.053259668508284 ms/frame
02:42:12 INFO - PID 1494 |
02:42:12 INFO - PID 1494 | Cycle 1(6): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:42:58 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827453729669097 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.671854636591479 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.795466786355476 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7684438549955797 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.244349363507781 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.134390495867769 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.5683174791914385 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.450275862068965 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.760095969289824 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.650833333333335 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.525301587301591 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.303790697674412 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239194756554314 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.814729729729722 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397913223140488 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.838994082840243 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.36583333333334 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990989010989015 ms/frame
02:42:58 INFO - PID 1494 |
02:42:58 INFO - PID 1494 | Cycle 1(7): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:43:44 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6916375422773395 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6714912280701755 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7364814814814817 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7606602112676062 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115288065843622 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.109075975359344 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.460963091118799 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.407504258943785 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693197343453508 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589553072625702 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.495110759493677 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 8.9714798206278 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.324509433962273 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.053038674033141 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.39832644628101 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.049186746987957 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.19486940298507 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.815918918918918 ms/frame
02:43:44 INFO - PID 1494 |
02:43:44 INFO - PID 1494 | Cycle 1(8): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:44:30 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6831323611890074 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668653044203503 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7799317912218269 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7625594713656396 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.126616231086659 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.193867924528303 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.624003623188405 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5603825622775824 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6508474576271155 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.542036011080341 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 10.070385906040276 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.902846534653465 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.453148854961812 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.638537234042582 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.399173553719002 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.909672619047646 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.409315589353618 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.873913043478277 ms/frame
02:44:30 INFO - PID 1494 |
02:44:30 INFO - PID 1494 | Cycle 1(9): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:45:16 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6818581838565023 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6704006677796326 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.780504451038576 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7516681260945708 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.155699445983381 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.117273662551442 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.6502737226277366 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.617748643761305 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.759702495201536 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.573690807799438 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 10.001066666666667 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.527142857142843 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.366590909090904 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.932923497267751 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.298176229508217 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.768264705882329 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.719121093750005 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.055690607734777 ms/frame
02:45:16 INFO - PID 1494 |
02:45:16 INFO - PID 1494 | Cycle 1(10): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:46:02 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6856544943820226 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6702212020033387 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7946232057416271 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7928539426523304 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 3.938228346456693 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.177588726513571 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.584731182795699 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5403185840707945 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.803916827852998 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.587136871508379 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.61543269230769 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.61600961538462 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.543576923076916 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.75688172043014 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.66014767932487 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.98230538922159 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.19447761194028 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.759032258064519 ms/frame
02:46:02 INFO - PID 1494 |
02:46:02 INFO - PID 1494 | Cycle 1(11): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:46:49 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_startup = 1.684825940482875 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669891485809683 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.799151678657074 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.748710664335665 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_startup = 4.127592847317745 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.091728016359921 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_startup = 3.658896341463415 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5781305903398914 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.770749999999994 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.496250000000005 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_startup = 9.587092651757194 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.480p.60fps.webm_scale_2_inclip = 9.528190476190467 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.496091954022987 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930327868852459 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296331967213115 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.765323529411742 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.194440298507464 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.813864864864838 ms/frame
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | Cycle 1(12): loaded http://localhost:51778/tests/video/video_playback.html (next: http://localhost:51778/tests/video/video_playback.html)
02:46:49 INFO - PID 1494 | __start_tp_report
02:46:49 INFO - PID 1494 | _x_x_mozilla_page_load
02:46:49 INFO - PID 1494 | _x_x_mozilla_page_load_details
02:46:49 INFO - PID 1494 | |i|pagename|runs|
02:46:49 INFO - PID 1494 | |0;240p.120fps.mp4_scale_1_startup;1.6805291153415454;1.682812675266405;1.6883652222847496;1.686002808988764;1.686523327712198;1.6818974215246636;1.6827453729669097;1.6916375422773395;1.6831323611890074;1.6818581838565023;1.6856544943820226;1.684825940482875
02:46:49 INFO - PID 1494 | |1;240p.120fps.mp4_scale_1_inclip;1.6699457429048412;1.6712406015037593;1.6670083333333336;1.6726128762541808;1.6716290726817042;1.6698080133555928;1.671854636591479;1.6714912280701755;1.668653044203503;1.6704006677796326;1.6702212020033387;1.669891485809683
02:46:49 INFO - PID 1494 | |2;240p.120fps.mp4_scale_1.1_startup;1.7478013977868372;1.779614472123369;1.749326530612245;1.759885630498534;1.7546315789473685;1.7585873388042201;1.795466786355476;1.7364814814814817;1.7799317912218269;1.780504451038576;1.7946232057416271;1.799151678657074
02:46:49 INFO - PID 1494 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7575659050966612;1.7396130434782604;1.7468602620087326;1.7259188955996543;1.7291356957649089;1.7310164359861595;1.7684438549955797;1.7606602112676062;1.7625594713656396;1.7516681260945708;1.7928539426523304;1.748710664335665
02:46:49 INFO - PID 1494 | |4;240p.120fps.mp4_scale_2_startup;4.093751705320599;4.049730094466937;4.105075239398084;4.1214354395604405;4.1678194444444445;4.087813351498638;4.244349363507781;4.115288065843622;4.126616231086659;4.155699445983381;3.938228346456693;4.127592847317745
02:46:49 INFO - PID 1494 | |5;240p.120fps.mp4_scale_2_inclip;4.05005060728745;4.073482688391039;4.049605263157897;4.177411273486429;4.125865979381444;4.107864476386038;4.134390495867769;4.109075975359344;4.193867924528303;4.117273662551442;4.177588726513571;4.091728016359921
02:46:49 INFO - PID 1494 | |6;480p.60fps.webm_scale_1_startup;3.5138583138173294;3.469364161849711;3.6023229291716694;3.477207415990728;3.615457831325301;3.6236654589371966;3.5683174791914385;3.460963091118799;3.624003623188405;3.6502737226277366;3.584731182795699;3.658896341463415
02:46:49 INFO - PID 1494 | |7;480p.60fps.webm_scale_1_inclip;3.455742659758204;3.3732546374367627;3.565784313725488;3.436838487972511;3.5530106571936075;3.5721785714285748;3.450275862068965;3.407504258943785;3.5603825622775824;3.617748643761305;3.5403185840707945;3.5781305903398914
02:46:49 INFO - PID 1494 | |8;480p.60fps.webm_scale_1.1_startup;5.445862068965514;5.545397412199633;5.693548387096774;5.770048076923075;5.639323308270682;5.715409523809524;5.760095969289824;5.693197343453508;5.6508474576271155;5.759702495201536;5.803916827852998;5.770749999999994
02:46:49 INFO - PID 1494 | |9;480p.60fps.webm_scale_1.1_inclip;5.5876675977653685;5.682769886363634;5.57139275766017;5.589413407821224;5.749942528735631;5.60393557422969;5.650833333333335;5.589553072625702;5.542036011080341;5.573690807799438;5.587136871508379;5.496250000000005
02:46:49 INFO - PID 1494 | |10;480p.60fps.webm_scale_2_startup;9.288931888544894;9.837065573770493;10.205850340136056;10.001066666666667;9.5564331210191;9.902326732673263;9.525301587301591;9.495110759493677;10.070385906040276;10.001066666666667;9.61543269230769;9.587092651757194
02:46:49 INFO - PID 1494 | |11;480p.60fps.webm_scale_2_inclip;9.5277380952381;9.90329207920792;9.348200934579436;9.709174757281554;9.049954751131226;9.618245192307699;9.303790697674412;8.9714798206278;9.902846534653465;9.527142857142843;9.61600961538462;9.528190476190467
02:46:49 INFO - PID 1494 | |12;1080p.60fps.mp4_scale_1_startup;11.282575187969915;11.365075757575747;11.28082706766919;11.409866920152098;11.364772727272724;11.368541666666674;11.239194756554314;11.324509433962273;11.453148854961812;11.366590909090904;11.543576923076916;11.496091954022987
02:46:49 INFO - PID 1494 | |13;1080p.60fps.mp4_scale_1_inclip;10.641303191489374;11.112694444444465;10.873451086956504;10.871983695652172;10.93021857923499;10.989752747252759;10.814729729729722;11.053038674033141;10.638537234042582;10.932923497267751;10.75688172043014;10.930327868852459
02:46:49 INFO - PID 1494 | |14;1080p.60fps.mp4_scale_1.1_startup;12.296782786885249;12.448423236514522;12.501166666666661;12.39787190082646;12.605147058823535;12.146012145748967;12.397913223140488;12.39832644628101;12.399173553719002;12.298176229508217;12.66014767932487;12.296331967213115
02:46:49 INFO - PID 1494 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.83434911242602;11.977095808383208;12.051867469879522;11.837100591715982;11.697251461988323;11.699678362573124;11.838994082840243;12.049186746987957;11.909672619047646;11.768264705882329;11.98230538922159;11.765323529411742
02:46:49 INFO - PID 1494 | |16;1080p.60fps.mp4_scale_2_startup;11.408365019011413;11.498103448275852;11.53874999999999;11.367518939393944;11.324301886792462;11.676906614786008;11.36583333333334;11.19486940298507;11.409315589353618;11.719121093750005;11.19447761194028;11.194440298507464
02:46:49 INFO - PID 1494 | |17;1080p.60fps.mp4_scale_2_inclip;10.699197860962567;10.87119565217393;11.0507182320442;10.871141304347791;10.696524064171124;11.053259668508284;10.990989010989015;10.815918918918918;10.873913043478277;11.055690607734777;10.759032258064519;10.813864864864838
02:46:49 INFO - PID 1494 | __end_tp_report
02:46:49 INFO - PID 1494 | __start_cc_report
02:46:49 INFO - PID 1494 | _x_x_mozilla_cycle_collect,596
02:46:49 INFO - PID 1494 | __end_cc_report
02:46:49 INFO - PID 1494 | __startTimestamp1507369609293__endTimestamp
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | ------- Summary: start -------
02:46:49 INFO - PID 1494 | Number of tests: 18
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.69 stddev:0.00 (0.2%) stddev-sans-first:0.00
02:46:49 INFO - PID 1494 | 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
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#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
02:46:49 INFO - PID 1494 | 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
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#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
02:46:49 INFO - PID 1494 | Values: 1.7 1.8 1.7 1.8 1.8 1.8 1.8 1.7 1.8 1.8 1.8 1.8
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.75 stddev:0.02 (1.1%) stddev-sans-first:0.02
02:46:49 INFO - PID 1494 | Values: 1.8 1.7 1.7 1.7 1.7 1.7 1.8 1.8 1.8 1.8 1.8 1.7
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.11 Median:4.12 stddev:0.07 (1.8%) stddev-sans-first:0.08
02:46:49 INFO - PID 1494 | Values: 4.1 4.0 4.1 4.1 4.2 4.1 4.2 4.1 4.1 4.2 3.9 4.1
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.12 Median:4.12 stddev:0.05 (1.2%) stddev-sans-first:0.05
02:46:49 INFO - PID 1494 | Values: 4.1 4.1 4.0 4.2 4.1 4.1 4.1 4.1 4.2 4.1 4.2 4.1
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.57 Median:3.61 stddev:0.07 (2.0%) stddev-sans-first:0.07
02:46:49 INFO - PID 1494 | Values: 3.5 3.5 3.6 3.5 3.6 3.6 3.6 3.5 3.6 3.7 3.6 3.7
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.51 Median:3.56 stddev:0.08 (2.2%) stddev-sans-first:0.08
02:46:49 INFO - PID 1494 | Values: 3.5 3.4 3.6 3.4 3.6 3.6 3.5 3.4 3.6 3.6 3.5 3.6
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.69 Median:5.74 stddev:0.10 (1.8%) stddev-sans-first:0.08
02:46:49 INFO - PID 1494 | Values: 5.4 5.5 5.7 5.8 5.6 5.7 5.8 5.7 5.7 5.8 5.8 5.8
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.60 Median:5.59 stddev:0.07 (1.2%) stddev-sans-first:0.07
02:46:49 INFO - PID 1494 | Values: 5.6 5.7 5.6 5.6 5.7 5.6 5.7 5.6 5.5 5.6 5.6 5.5
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.76 Median:9.54 stddev:0.28 (3.0%) stddev-sans-first:0.25
02:46:49 INFO - PID 1494 | Values: 9.3 9.8 10.2 10.0 9.6 9.9 9.5 9.5 10.1 10.0 9.6 9.6
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.50 Median:9.57 stddev:0.29 (3.1%) stddev-sans-first:0.31
02:46:49 INFO - PID 1494 | Values: 9.5 9.9 9.3 9.7 9.0 9.6 9.3 9.0 9.9 9.5 9.6 9.5
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.37 Median:11.37 stddev:0.09 (0.8%) stddev-sans-first:0.09
02:46:49 INFO - PID 1494 | Values: 11.3 11.4 11.3 11.4 11.4 11.4 11.2 11.3 11.5 11.4 11.5 11.5
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.88 Median:10.93 stddev:0.15 (1.3%) stddev-sans-first:0.13
02:46:49 INFO - PID 1494 | Values: 10.6 11.1 10.9 10.9 10.9 11.0 10.8 11.1 10.6 10.9 10.8 10.9
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.40 Median:12.40 stddev:0.14 (1.1%) stddev-sans-first:0.14
02:46:49 INFO - PID 1494 | Values: 12.3 12.4 12.5 12.4 12.6 12.1 12.4 12.4 12.4 12.3 12.7 12.3
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.87 Median:11.87 stddev:0.13 (1.1%) stddev-sans-first:0.13
02:46:49 INFO - PID 1494 | Values: 11.8 12.0 12.1 11.8 11.7 11.7 11.8 12.0 11.9 11.8 12.0 11.8
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.41 Median:11.41 stddev:0.18 (1.5%) stddev-sans-first:0.18
02:46:49 INFO - PID 1494 | Values: 11.4 11.5 11.5 11.4 11.3 11.7 11.4 11.2 11.4 11.7 11.2 11.2
02:46:49 INFO - PID 1494 |
02:46:49 INFO - PID 1494 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.88 Median:10.87 stddev:0.13 (1.2%) stddev-sans-first:0.13
02:46:49 INFO - PID 1494 | Values: 10.7 10.9 11.1 10.9 10.7 11.1 11.0 10.8 10.9 11.1 10.8 10.8
02:46:49 INFO - PID 1494 | -------- Summary: end --------
02:46:49 INFO - PID 1494 |
02:46:49 INFO - TEST-INFO | 1494: exit 0
02:46:49 INFO - TEST-OK | basic_compositor_video | took 560592ms
02:46:49 INFO - TEST-START | glvideo
02:46:49 INFO - Initialising browser for glvideo test...
02:46:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:51778/getInfo.html -profile /tmp/tmpxTkDBd/profile
02:46:49 INFO - TEST-INFO | started process 2940 (/builds/slave/test/build/application/firefox/firefox http://localhost:51778/getInfo.html)
02:46:54 INFO - TEST-INFO | 2940: exit 0
02:46:54 INFO - Browser initialized.
02:46:54 INFO - Running cycle 1/1 for glvideo test...
02:46:54 INFO - Using env: {'DISPLAY': ':0',
02:46:54 INFO - 'HOME': '/home/cltbld',
02:46:54 INFO - 'JSGC_DISABLE_POISONING': '1',
02:46:54 INFO - 'LANG': 'en_US.UTF-8',
02:46:54 INFO - 'LANGUAGE': 'en_US:en',
02:46:54 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:46:54 INFO - 'LOGNAME': 'cltbld',
02:46:54 INFO - 'MAIL': '/var/mail/cltbld',
02:46:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:46:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:46:54 INFO - 'MOZ_CRASHREPORTER': '1',
02:46:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:46:54 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:46:54 INFO - 'MOZ_NO_REMOTE': '1',
02:46:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:46:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:46:54 INFO - 'NO_EM_RESTART': '1',
02:46:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:46:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:46:54 INFO - 'PWD': '/builds/slave/test',
02:46:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:46:54 INFO - 'RUST_BACKTRACE': 'full',
02:46:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:46:54 INFO - 'SHELL': '/bin/bash',
02:46:54 INFO - 'SHLVL': '1',
02:46:54 INFO - 'STYLO_FORCE_DISABLED': '1',
02:46:54 INFO - 'TERM': 'linux',
02:46:54 INFO - 'TMOUT': '86400',
02:46:54 INFO - 'USER': 'cltbld',
02:46:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351',
02:46:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:46:54 INFO - '_': '/tools/buildbot/bin/python'}
02:46:54 INFO - TEST-INFO | started process 3317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxTkDBd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
02:46:55 INFO - PID 3317 |
02:46:55 INFO - PID 3317 | (/builds/slave/test/build/application/firefox/firefox:3366): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:46:55 INFO - PID 3317 |
02:46:57 INFO - PID 3317 | [talos glvideo result] Mean tick time across 100 ticks: 7.6987 ms
02:46:57 INFO - PID 3317 | Cycle 1(1): loaded http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html)
02:46:58 INFO - PID 3317 | [talos glvideo result] Mean tick time across 100 ticks: 6.5596000000000005 ms
02:46:58 INFO - PID 3317 | Cycle 1(2): loaded http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html)
02:46:59 INFO - PID 3317 | [talos glvideo result] Mean tick time across 100 ticks: 6.55875 ms
02:46:59 INFO - PID 3317 | Cycle 1(3): loaded http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html)
02:47:01 INFO - PID 3317 | [talos glvideo result] Mean tick time across 100 ticks: 6.621700000000001 ms
02:47:01 INFO - PID 3317 | Cycle 1(4): loaded http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html)
02:47:02 INFO - PID 3317 | [talos glvideo result] Mean tick time across 100 ticks: 6.6487 ms
02:47:02 INFO - PID 3317 | Cycle 1(5): loaded http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:51778/tests/webgl/benchmarks/video/video_upload.html)
02:47:02 INFO - PID 3317 | __start_tp_report
02:47:02 INFO - PID 3317 | _x_x_mozilla_page_load
02:47:02 INFO - PID 3317 | _x_x_mozilla_page_load_details
02:47:02 INFO - PID 3317 | |i|pagename|runs|
02:47:02 INFO - PID 3317 | |0;Mean tick time across 100 ticks: ;7.6987;6.5596000000000005;6.55875;6.621700000000001;6.6487
02:47:02 INFO - PID 3317 | __end_tp_report
02:47:02 INFO - PID 3317 | __start_cc_report
02:47:02 INFO - PID 3317 | _x_x_mozilla_cycle_collect,277
02:47:02 INFO - PID 3317 | __end_cc_report
02:47:02 INFO - PID 3317 | __startTimestamp1507369622796__endTimestamp
02:47:02 INFO - PID 3317 |
02:47:02 INFO - PID 3317 | ------- Summary: start -------
02:47:02 INFO - PID 3317 | Number of tests: 1
02:47:02 INFO - PID 3317 |
02:47:02 INFO - PID 3317 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.82 Median:6.62 stddev:0.49 (7.5%) stddev-sans-first:0.05
02:47:02 INFO - PID 3317 | Values: 7.7 6.6 6.6 6.6 6.6
02:47:02 INFO - PID 3317 | -------- Summary: end --------
02:47:02 INFO - PID 3317 |
02:47:03 INFO - PID 3317 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
02:47:03 INFO - TEST-INFO | 3317: exit 0
02:47:03 INFO - TEST-OK | glvideo | took 13653ms
02:47:03 INFO - SUITE-END | took 574s
02:47:03 INFO - Completed test suite (00:09:34)
02:47:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.600813180336984, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6805291153415454, 1.682812675266405, 1.6883652222847496, 1.686002808988764, 1.686523327712198, 1.6818974215246636, 1.6827453729669097, 1.6916375422773395, 1.6831323611890074, 1.6818581838565023, 1.6856544943820226, 1.684825940482875], "value": 1.684825940482875, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6699457429048412, 1.6712406015037593, 1.6670083333333336, 1.6726128762541808, 1.6716290726817042, 1.6698080133555928, 1.671854636591479, 1.6714912280701755, 1.668653044203503, 1.6704006677796326, 1.6702212020033387, 1.669891485809683], "value": 1.6704006677796326, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7478013977868372, 1.779614472123369, 1.749326530612245, 1.759885630498534, 1.7546315789473685, 1.7585873388042201, 1.795466786355476, 1.7364814814814817, 1.7799317912218269, 1.780504451038576, 1.7946232057416271, 1.799151678657074], "value": 1.779614472123369, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7575659050966612, 1.7396130434782604, 1.7468602620087326, 1.7259188955996543, 1.7291356957649089, 1.7310164359861595, 1.7684438549955797, 1.7606602112676062, 1.7625594713656396, 1.7516681260945708, 1.7928539426523304, 1.748710664335665], "value": 1.748710664335665, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.093751705320599, 4.049730094466937, 4.105075239398084, 4.1214354395604405, 4.1678194444444445, 4.087813351498638, 4.244349363507781, 4.115288065843622, 4.126616231086659, 4.155699445983381, 3.938228346456693, 4.127592847317745], "value": 4.1214354395604405, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.05005060728745, 4.073482688391039, 4.049605263157897, 4.177411273486429, 4.125865979381444, 4.107864476386038, 4.134390495867769, 4.109075975359344, 4.193867924528303, 4.117273662551442, 4.177588726513571, 4.091728016359921], "value": 4.117273662551442, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5138583138173294, 3.469364161849711, 3.6023229291716694, 3.477207415990728, 3.615457831325301, 3.6236654589371966, 3.5683174791914385, 3.460963091118799, 3.624003623188405, 3.6502737226277366, 3.584731182795699, 3.658896341463415], "value": 3.6023229291716694, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.455742659758204, 3.3732546374367627, 3.565784313725488, 3.436838487972511, 3.5530106571936075, 3.5721785714285748, 3.450275862068965, 3.407504258943785, 3.5603825622775824, 3.617748643761305, 3.5403185840707945, 3.5781305903398914], "value": 3.5530106571936075, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.445862068965514, 5.545397412199633, 5.693548387096774, 5.770048076923075, 5.639323308270682, 5.715409523809524, 5.760095969289824, 5.693197343453508, 5.6508474576271155, 5.759702495201536, 5.803916827852998, 5.770749999999994], "value": 5.715409523809524, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.5876675977653685, 5.682769886363634, 5.57139275766017, 5.589413407821224, 5.749942528735631, 5.60393557422969, 5.650833333333335, 5.589553072625702, 5.542036011080341, 5.573690807799438, 5.587136871508379, 5.496250000000005], "value": 5.589413407821224, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.288931888544894, 9.837065573770493, 10.205850340136056, 10.001066666666667, 9.5564331210191, 9.902326732673263, 9.525301587301591, 9.495110759493677, 10.070385906040276, 10.001066666666667, 9.61543269230769, 9.587092651757194], "value": 9.837065573770493, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.5277380952381, 9.90329207920792, 9.348200934579436, 9.709174757281554, 9.049954751131226, 9.618245192307699, 9.303790697674412, 8.9714798206278, 9.902846534653465, 9.527142857142843, 9.61600961538462, 9.528190476190467], "value": 9.528190476190467, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.282575187969915, 11.365075757575747, 11.28082706766919, 11.409866920152098, 11.364772727272724, 11.368541666666674, 11.239194756554314, 11.324509433962273, 11.453148854961812, 11.366590909090904, 11.543576923076916, 11.496091954022987], "value": 11.366590909090904, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.641303191489374, 11.112694444444465, 10.873451086956504, 10.871983695652172, 10.93021857923499, 10.989752747252759, 10.814729729729722, 11.053038674033141, 10.638537234042582, 10.932923497267751, 10.75688172043014, 10.930327868852459], "value": 10.93021857923499, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.296782786885249, 12.448423236514522, 12.501166666666661, 12.39787190082646, 12.605147058823535, 12.146012145748967, 12.397913223140488, 12.39832644628101, 12.399173553719002, 12.298176229508217, 12.66014767932487, 12.296331967213115], "value": 12.39832644628101, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.83434911242602, 11.977095808383208, 12.051867469879522, 11.837100591715982, 11.697251461988323, 11.699678362573124, 11.838994082840243, 12.049186746987957, 11.909672619047646, 11.768264705882329, 11.98230538922159, 11.765323529411742], "value": 11.838994082840243, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.408365019011413, 11.498103448275852, 11.53874999999999, 11.367518939393944, 11.324301886792462, 11.676906614786008, 11.36583333333334, 11.19486940298507, 11.409315589353618, 11.719121093750005, 11.19447761194028, 11.194440298507464], "value": 11.367518939393944, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.699197860962567, 10.87119565217393, 11.0507182320442, 10.871141304347791, 10.696524064171124, 11.053259668508284, 10.990989010989015, 10.815918918918918, 10.873913043478277, 11.055690607734777, 10.759032258064519, 10.813864864864838], "value": 10.87119565217393, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.6987, 6.5596000000000005, 6.55875, 6.621700000000001, 6.6487], "value": 6.59065, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
02:47:03 INFO - Return code: 0
02:47:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
02:47:03 INFO - # TBPL SUCCESS #
02:47:03 INFO - Running post-action listener: _package_coverage_data
02:47:03 INFO - Running post-action listener: _resource_record_post_action
02:47:03 INFO - [mozharness: 2017-10-07 09:47:03.964936Z] Finished run-tests step (success)
02:47:03 INFO - Running post-run listener: _resource_record_post_run
02:47:04 INFO - Total resource usage - Wall time: 593s; CPU: 39.0%; Read bytes: 8773632; Write bytes: 307449856; Read time: 428; Write time: 171568
02:47:04 INFO - TinderboxPrint: CPU usage
38.7%
02:47:04 INFO - TinderboxPrint: I/O read bytes / time
8,773,632 / 428
02:47:04 INFO - TinderboxPrint: I/O write bytes / time
307,449,856 / 171,568
02:47:04 INFO - TinderboxPrint: CPU idle
2,852.2 (61.3%)
02:47:04 INFO - TinderboxPrint: CPU system
299.2 (6.4%)
02:47:04 INFO - TinderboxPrint: CPU user
1,489.5 (32.0%)
02:47:04 INFO - TinderboxPrint: Swap in / out
0 / 0
02:47:04 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 512000; Read time: 0; Write time: 44
02:47:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
02:47:04 INFO - run-tests - Wall time: 575s; CPU: 40.0%; Read bytes: 8773632; Write bytes: 306937856; Read time: 428; Write time: 171524
02:47:04 INFO - Running post-run listener: _upload_blobber_files
02:47:04 INFO - Blob upload gear active.
02:47:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
02:47:04 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
02:47:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
02:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
02:47:04 INFO - /builds/slave/test/build/venv/local/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
02:47:04 INFO - SNIMissingWarning
02:47:04 INFO - /builds/slave/test/build/venv/local/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
02:47:04 INFO - InsecurePlatformWarning
02:47:04 INFO - /builds/slave/test/build/venv/local/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.)
02:47:04 INFO - SubjectAltNameWarning
02:47:04 INFO - (blobuploader) - INFO - Open directory for files ...
02:47:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
02:47:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:47:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:47:04 INFO - /builds/slave/test/build/venv/local/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
02:47:04 INFO - InsecurePlatformWarning
02:47:04 INFO - /builds/slave/test/build/venv/local/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.)
02:47:04 INFO - SubjectAltNameWarning
02:47:05 INFO - /builds/slave/test/build/venv/local/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
02:47:05 INFO - InsecurePlatformWarning
02:47:05 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
02:47:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:47:05 INFO - (blobuploader) - INFO - Done attempting.
02:47:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
02:47:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:47:05 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:47:05 INFO - /builds/slave/test/build/venv/local/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
02:47:05 INFO - InsecurePlatformWarning
02:47:05 INFO - /builds/slave/test/build/venv/local/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.)
02:47:05 INFO - SubjectAltNameWarning
02:47:10 INFO - /builds/slave/test/build/venv/local/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
02:47:10 INFO - InsecurePlatformWarning
02:47:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
02:47:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:47:10 INFO - (blobuploader) - INFO - Done attempting.
02:47:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
02:47:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:47:10 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:47:10 INFO - /builds/slave/test/build/venv/local/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
02:47:10 INFO - InsecurePlatformWarning
02:47:11 INFO - /builds/slave/test/build/venv/local/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.)
02:47:11 INFO - SubjectAltNameWarning
02:47:11 INFO - (blobuploader) - CRITICAL - Something went wrong on blobber!
02:47:11 INFO - (blobuploader) - INFO - Upload failed. Trying again ...
02:47:11 INFO - (blobuploader) - INFO - Done attempting.
02:47:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
02:47:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:47:11 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:47:11 INFO - /builds/slave/test/build/venv/local/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
02:47:11 INFO - InsecurePlatformWarning
02:47:11 INFO - /builds/slave/test/build/venv/local/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.)
02:47:11 INFO - SubjectAltNameWarning
02:47:11 INFO - /builds/slave/test/build/venv/local/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
02:47:11 INFO - InsecurePlatformWarning
02:47:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
02:47:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:47:12 INFO - (blobuploader) - INFO - Done attempting.
02:47:12 INFO - (blobuploader) - INFO - Iteration through files over.
02:47:12 INFO - Return code: 0
02:47:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
02:47:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
02:47:12 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a732a6224be9c0c74882facb67a3b40ec8d3915cf98d8e9d0ecbc6ea712aedc7b685efed7447a94139eda384360ad993ba8c5ff2d0d1a2efb90789b40e84cea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/199df1c013cfa6cb38fa27d91c489a95d73ca3605d188fed8c26f5e13467619b5a615406322cec163aef839f0d4d765e72a093d2fc91786006b8a49b29f55f75", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff15d1551001912d4598fb245991aff93f9cb6195a370fb7b78f472c4886ea84a29db8101e2b1a5cecf9ce497ef371ecf0c450e0d72455b5e9ec08c905d07065", "g4-stylo-disabled-e10s_raw.log": null}
02:47:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
02:47:12 INFO - Writing to file /builds/slave/test/properties/blobber_files
02:47:12 INFO - Contents:
02:47:12 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a732a6224be9c0c74882facb67a3b40ec8d3915cf98d8e9d0ecbc6ea712aedc7b685efed7447a94139eda384360ad993ba8c5ff2d0d1a2efb90789b40e84cea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/199df1c013cfa6cb38fa27d91c489a95d73ca3605d188fed8c26f5e13467619b5a615406322cec163aef839f0d4d765e72a093d2fc91786006b8a49b29f55f75", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff15d1551001912d4598fb245991aff93f9cb6195a370fb7b78f472c4886ea84a29db8101e2b1a5cecf9ce497ef371ecf0c450e0d72455b5e9ec08c905d07065", "g4-stylo-disabled-e10s_raw.log": null}
02:47:12 INFO - Running post-run listener: copy_logs_to_upload_dir
02:47:12 INFO - Copying logs to upload dir...
02:47:12 INFO - mkdir: /builds/slave/test/build/upload/logs
02:47:12 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=705.453362
========= master_lag: 0.09 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 45 secs) (at 2017-10-07 02:47:12.168224) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-07 02:47:12.172542) =========
bash -c 'for file in `ls -1`; do cat $file; done'
in dir /builds/slave/test/properties (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test/properties
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a732a6224be9c0c74882facb67a3b40ec8d3915cf98d8e9d0ecbc6ea712aedc7b685efed7447a94139eda384360ad993ba8c5ff2d0d1a2efb90789b40e84cea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/199df1c013cfa6cb38fa27d91c489a95d73ca3605d188fed8c26f5e13467619b5a615406322cec163aef839f0d4d765e72a093d2fc91786006b8a49b29f55f75", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff15d1551001912d4598fb245991aff93f9cb6195a370fb7b78f472c4886ea84a29db8101e2b1a5cecf9ce497ef371ecf0c450e0d72455b5e9ec08c905d07065", "g4-stylo-disabled-e10s_raw.log": null}
build_url:https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011327
build_url: 'https://queue.taskcluster.net/v1/task/diuBdHD5SYu2aC0-wpyYfg/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a732a6224be9c0c74882facb67a3b40ec8d3915cf98d8e9d0ecbc6ea712aedc7b685efed7447a94139eda384360ad993ba8c5ff2d0d1a2efb90789b40e84cea4", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/199df1c013cfa6cb38fa27d91c489a95d73ca3605d188fed8c26f5e13467619b5a615406322cec163aef839f0d4d765e72a093d2fc91786006b8a49b29f55f75", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ff15d1551001912d4598fb245991aff93f9cb6195a370fb7b78f472c4886ea84a29db8101e2b1a5cecf9ce497ef371ecf0c450e0d72455b5e9ec08c905d07065", "g4-stylo-disabled-e10s_raw.log": null}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-07 02:47:12.201355) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:47:12.201675) =========
rm -f oauth.txt
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=41bf3e9aed84707956354da8000001c5-1507364085.771749-888984351
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004589
========= master_lag: 0.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-07 02:47:12.254954) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-07 02:47:12.257275) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-07 02:47:12.257560) =========
========= Total master_lag: 0.32 =========