builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-063
starttime: 1509183325.29
results: success (0)
revision: c16bc8097c102cdd2b70d8e7db3656222455a6ed
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.294720) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.295128) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.295394) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.339126) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.339450) =========
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=769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-28 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.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 103M=0s
2017-10-28 02:35:25 (103 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.168013
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.538459) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.538748) =========
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=769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.022891
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:25.592707) =========
========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-10-28 02:35:25.595957) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c16bc8097c102cdd2b70d8e7db3656222455a6ed --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 c16bc8097c102cdd2b70d8e7db3656222455a6ed --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=769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-28 02:35:25,649 truncating revision to first 12 chars
2017-10-28 02:35:25,649 Setting DEBUG logging.
2017-10-28 02:35:25,649 attempt 1/10
2017-10-28 02:35:25,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c16bc8097c10?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-28 02:35:26,745 unpacking tar archive at: mozilla-central-c16bc8097c10/testing/mozharness/
program finished with exit code 0
elapsedTime=12.940557
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-10-28 02:35:38.558017) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:38.558406) =========
script_repo_revision: c16bc8097c102cdd2b70d8e7db3656222455a6ed
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:38.558887) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:38.559149) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-28 02:35:38.574409) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 20 secs) (at 2017-10-28 02:35:38.575021) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
02:35:38 INFO - MultiFileLogger online at 20171028 02:35:38 in /builds/slave/test
02:35:38 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
02:35:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
02:35:38 INFO - {'append_to_log': False,
02:35:38 INFO - 'base_work_dir': '/builds/slave/test',
02:35:38 INFO - 'blob_upload_branch': 'Firefox',
02:35:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
02:35:38 INFO - 'branch': 'Firefox',
02:35:38 INFO - 'buildbot_json_path': 'buildprops.json',
02:35:38 INFO - 'code_coverage': False,
02:35:38 INFO - 'config_files': ('talos/linux_config.py',),
02:35:38 INFO - 'default_actions': ('clobber',
02:35:38 INFO - 'read-buildbot-config',
02:35:38 INFO - 'download-and-extract',
02:35:38 INFO - 'populate-webroot',
02:35:38 INFO - 'create-virtualenv',
02:35:38 INFO - 'install',
02:35:38 INFO - 'setup-mitmproxy',
02:35:38 INFO - 'run-tests'),
02:35:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:35:38 INFO - 'disable_ccov_upload': False,
02:35:38 INFO - 'disable_stylo': False,
02:35:38 INFO - 'download_minidump_stackwalk': True,
02:35:38 INFO - 'download_symbols': 'ondemand',
02:35:38 INFO - 'enable_stylo': False,
02:35:38 INFO - 'enable_webrender': False,
02:35:38 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
02:35:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:35:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:35:38 INFO - 'gecko_profile': False,
02:35:38 INFO - 'gecko_profile_interval': 0,
02:35:38 INFO - 'installer_path': 'installer.exe',
02:35:38 INFO - 'log_level': 'info',
02:35:38 INFO - 'log_name': 'talos',
02:35:38 INFO - 'log_to_console': True,
02:35:38 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
02:35:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
02:35:38 INFO - 'opt_config_files': (),
02:35:38 INFO - 'pip_index': False,
02:35:38 INFO - 'suite': 'g4-e10s',
02:35:38 INFO - 'system_bits': '32',
02:35:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:35:38 INFO - 'title': 'talos-linux64-ix-063',
02:35:38 INFO - 'tooltool_cache': '/builds/tooltool_cache',
02:35:38 INFO - 'use_talos_json': True,
02:35:38 INFO - 'verify': 'False',
02:35:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
02:35:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:35:38 INFO - 'work_dir': 'build'}
02:35:38 INFO - [mozharness: 2017-10-28 09:35:38.767286Z] Running clobber step.
02:35:38 INFO - Running pre-action listener: _resource_record_pre_action
02:35:38 INFO - Running main action method: clobber
02:35:38 INFO - rmtree: /builds/slave/test/build
02:35:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
02:35:39 INFO - Running post-action listener: _resource_record_post_action
02:35:39 INFO - [mozharness: 2017-10-28 09:35:39.853401Z] Finished clobber step (success)
02:35:39 INFO - [mozharness: 2017-10-28 09:35:39.853499Z] Running read-buildbot-config step.
02:35:39 INFO - Running pre-action listener: _resource_record_pre_action
02:35:39 INFO - Running main action method: read_buildbot_config
02:35:39 INFO - Using buildbot properties:
02:35:39 INFO - {
02:35:39 INFO - "project": "",
02:35:39 INFO - "product": "firefox",
02:35:39 INFO - "who": "archaeopteryx@coole-files.de",
02:35:39 INFO - "installer_path": "public/build/target.tar.bz2",
02:35:39 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
02:35:39 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
02:35:39 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
02:35:39 INFO - "stage_platform": "linux64-qr",
02:35:39 INFO - "basedir": "/builds/slave/test",
02:35:39 INFO - "buildnumber": 139,
02:35:39 INFO - "platform": "ubuntu64_hw_qr",
02:35:39 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
02:35:39 INFO - "slavebuilddir": "test",
02:35:39 INFO - "taskId": "XwaXb1i-QBq3z5NttwEkQA",
02:35:39 INFO - "branch": "mozilla-central",
02:35:39 INFO - "script_repo_revision": "production",
02:35:39 INFO - "revision": "c16bc8097c102cdd2b70d8e7db3656222455a6ed",
02:35:39 INFO - "slavename": "talos-linux64-ix-063",
02:35:39 INFO - "repo_path": "mozilla-central"
02:35:39 INFO - }
02:35:39 INFO - Finding installer, test and symbols from parent task.
02:35:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XwaXb1i-QBq3z5NttwEkQA'}, attempt #1
02:35:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XwaXb1i-QBq3z5NttwEkQA'}, attempt #1
02:35:40 INFO - Task dependencies: aS3dd_IMTaiTzlVDHILLBA
02:35:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA'}, attempt #1
02:35:41 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2
02:35:41 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.test_packages.json
02:35:41 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.crashreporter-symbols.zip
02:35:41 INFO - Running post-action listener: _resource_record_post_action
02:35:41 INFO - [mozharness: 2017-10-28 09:35:41.387302Z] Finished read-buildbot-config step (success)
02:35:41 INFO - [mozharness: 2017-10-28 09:35:41.387522Z] Running download-and-extract step.
02:35:41 INFO - Running pre-action listener: _resource_record_pre_action
02:35:41 INFO - Running main action method: download_and_extract
02:35:41 INFO - mkdir: /builds/slave/test/build/tests
02:35:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:35:41 INFO - trying https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.test_packages.json
02:35:41 INFO - Downloading https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
02:35:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
02:35:41 INFO - Downloaded 989 bytes.
02:35:41 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
02:35:41 INFO - Using the following test package requirements:
02:35:41 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
02:35:41 INFO - u'common': [u'target.common.tests.zip'],
02:35:41 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
02:35:41 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
02:35:41 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
02:35:41 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
02:35:41 INFO - u'mozbase': [u'target.common.tests.zip'],
02:35:41 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
02:35:41 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
02:35:41 INFO - u'web-platform': [u'target.common.tests.zip',
02:35:41 INFO - u'target.web-platform.tests.tar.gz'],
02:35:41 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
02:35:41 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
02:35:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.common.tests.zip
02:35:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.common.tests.zip'}, attempt #1
02:35:41 INFO - Fetch https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.common.tests.zip into memory
02:35:57 INFO - Content-Length response header: 56328618
02:35:57 INFO - Bytes received: 56328618
02:36:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.talos.tests.zip
02:36:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.talos.tests.zip'}, attempt #1
02:36:08 INFO - Fetch https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.talos.tests.zip into memory
02:36:11 INFO - Content-Length response header: 18770379
02:36:11 INFO - Bytes received: 18770379
02:36:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:12 INFO - trying https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2
02:36:12 INFO - Downloading https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
02:36:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
02:36:20 INFO - Downloaded 62840134 bytes.
02:36:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2
02:36:20 INFO - mkdir: /builds/slave/test/properties
02:36:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
02:36:20 INFO - Writing to file /builds/slave/test/properties/build_url
02:36:20 INFO - Contents:
02:36:20 INFO - build_url:https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2
02:36:20 INFO - Running post-action listener: _resource_record_post_action
02:36:20 INFO - Running post-action listener: find_tests_for_verification
02:36:20 INFO - Running post-action listener: set_extra_try_arguments
02:36:20 INFO - [mozharness: 2017-10-28 09:36:20.571328Z] Finished download-and-extract step (success)
02:36:20 INFO - [mozharness: 2017-10-28 09:36:20.571425Z] Running populate-webroot step.
02:36:20 INFO - Running pre-action listener: _resource_record_pre_action
02:36:20 INFO - Running main action method: populate_webroot
02:36:20 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
02:36:20 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['tresize',
02:36:20 INFO - 'tcanvasmark']},
02:36:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
02:36:20 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['dromaeo_css',
02:36:20 INFO - 'kraken']},
02:36:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:36:20 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:36:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'tests': ['damp', 'tps']},
02:36:20 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['damp', 'tps']},
02:36:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
02:36:20 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['dromaeo_dom']},
02:36:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
02:36:20 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['basic_compositor_video',
02:36:20 INFO - 'glvideo']},
02:36:20 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
02:36:20 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['ts_paint_webext',
02:36:20 INFO - 'tp5o_webext']},
02:36:20 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
02:36:20 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:36:20 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:36:20 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:36:20 INFO - 'talos_options': ['--mitmproxy',
02:36:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:36:20 INFO - '--firstNonBlankPaint'],
02:36:20 INFO - 'tests': ['tp6_google_heavy',
02:36:20 INFO - 'tp6_youtube_heavy',
02:36:20 INFO - 'tp6_amazon_heavy',
02:36:20 INFO - 'tp6_facebook_heavy']},
02:36:20 INFO - 'other-e10s': {'tests': ['a11yr',
02:36:20 INFO - 'ts_paint',
02:36:20 INFO - 'tpaint',
02:36:20 INFO - 'sessionrestore',
02:36:20 INFO - 'sessionrestore_many_windows',
02:36:20 INFO - 'sessionrestore_no_auto_restore',
02:36:20 INFO - 'tabpaint',
02:36:20 INFO - 'cpstartup']},
02:36:20 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['a11yr',
02:36:20 INFO - 'ts_paint',
02:36:20 INFO - 'tpaint',
02:36:20 INFO - 'sessionrestore',
02:36:20 INFO - 'sessionrestore_many_windows',
02:36:20 INFO - 'sessionrestore_no_auto_restore',
02:36:20 INFO - 'tabpaint',
02:36:20 INFO - 'cpstartup']},
02:36:20 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
02:36:20 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
02:36:20 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['perf_reftest_singletons']},
02:36:20 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['perf_reftest']},
02:36:20 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
02:36:20 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['speedometer']},
02:36:20 INFO - 'svgr-e10s': {'tests': ['tsvgx',
02:36:20 INFO - 'tsvgr_opacity',
02:36:20 INFO - 'tart',
02:36:20 INFO - 'tscrollx',
02:36:20 INFO - 'tsvg_static']},
02:36:20 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['tsvgx',
02:36:20 INFO - 'tsvgr_opacity',
02:36:20 INFO - 'tart',
02:36:20 INFO - 'tscrollx',
02:36:20 INFO - 'tsvg_static']},
02:36:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
02:36:20 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'talos_options': ['--disable-stylo'],
02:36:20 INFO - 'tests': ['tp5o']},
02:36:20 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:36:20 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:36:20 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:36:20 INFO - 'talos_options': ['--mitmproxy',
02:36:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:36:20 INFO - '--firstNonBlankPaint'],
02:36:20 INFO - 'tests': ['tp6_google',
02:36:20 INFO - 'tp6_youtube',
02:36:20 INFO - 'tp6_amazon',
02:36:20 INFO - 'tp6_facebook']},
02:36:20 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:36:20 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:36:20 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:36:20 INFO - 'talos_options': ['--disable-stylo',
02:36:20 INFO - '--mitmproxy',
02:36:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:36:20 INFO - '--firstNonBlankPaint'],
02:36:20 INFO - 'tests': ['tp6_google',
02:36:20 INFO - 'tp6_youtube',
02:36:20 INFO - 'tp6_amazon',
02:36:20 INFO - 'tp6_facebook']},
02:36:20 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:36:20 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:36:20 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:36:20 INFO - 'talos_options': ['--stylo-threads=1',
02:36:20 INFO - '--mitmproxy',
02:36:20 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:36:20 INFO - '--firstNonBlankPaint'],
02:36:20 INFO - 'tests': ['tp6_google',
02:36:20 INFO - 'tp6_youtube',
02:36:20 INFO - 'tp6_amazon',
02:36:20 INFO - 'tp6_facebook']},
02:36:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'talos_options': ['--xperf_path',
02:36:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:36:20 INFO - 'tests': ['tp5n']},
02:36:20 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:36:20 INFO - 'talos_options': ['--disable-stylo',
02:36:20 INFO - '--xperf_path',
02:36:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:36:20 INFO - 'tests': ['tp5n']}}}
02:36:20 INFO - Running post-action listener: _resource_record_post_action
02:36:20 INFO - [mozharness: 2017-10-28 09:36:20.589667Z] Finished populate-webroot step (success)
02:36:20 INFO - [mozharness: 2017-10-28 09:36:20.589756Z] Running create-virtualenv step.
02:36:20 INFO - Running pre-action listener: _resource_record_pre_action
02:36:20 INFO - Running main action method: create_virtualenv
02:36:20 INFO - Creating virtualenv /builds/slave/test/build/venv
02:36:20 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
02:36:20 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
02:36:20 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
02:36:20 INFO - Using real prefix '/usr'
02:36:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python
02:36:20 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
02:36:23 INFO - Installing setuptools, pip, wheel...done.
02:36:23 INFO - Return code: 0
02:36:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
02:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
02:36:23 INFO - Using env: {'DISPLAY': ':0',
02:36:23 INFO - 'HOME': '/home/cltbld',
02:36:23 INFO - 'LANG': 'en_US.UTF-8',
02:36:23 INFO - 'LANGUAGE': 'en_US:en',
02:36:23 INFO - 'LOGNAME': 'cltbld',
02:36:23 INFO - 'MAIL': '/var/mail/cltbld',
02:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:23 INFO - 'MOZ_NO_REMOTE': '1',
02:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:23 INFO - 'NO_EM_RESTART': '1',
02:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:23 INFO - 'PWD': '/builds/slave/test',
02:36:23 INFO - 'SHELL': '/bin/bash',
02:36:23 INFO - 'SHLVL': '1',
02:36:23 INFO - 'TERM': 'linux',
02:36:23 INFO - 'TMOUT': '86400',
02:36:23 INFO - 'USER': 'cltbld',
02:36:23 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:23 INFO - '_': '/tools/buildbot/bin/python'}
02:36:23 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
02:36:23 INFO - Return code: 0
02:36:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
02:36:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
02:36:23 INFO - Using env: {'DISPLAY': ':0',
02:36:23 INFO - 'HOME': '/home/cltbld',
02:36:23 INFO - 'LANG': 'en_US.UTF-8',
02:36:23 INFO - 'LANGUAGE': 'en_US:en',
02:36:23 INFO - 'LOGNAME': 'cltbld',
02:36:23 INFO - 'MAIL': '/var/mail/cltbld',
02:36:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:23 INFO - 'MOZ_NO_REMOTE': '1',
02:36:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:23 INFO - 'NO_EM_RESTART': '1',
02:36:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:23 INFO - 'PWD': '/builds/slave/test',
02:36:23 INFO - 'SHELL': '/bin/bash',
02:36:23 INFO - 'SHLVL': '1',
02:36:23 INFO - 'TERM': 'linux',
02:36:23 INFO - 'TMOUT': '86400',
02:36:23 INFO - 'USER': 'cltbld',
02:36:23 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:23 INFO - '_': '/tools/buildbot/bin/python'}
02:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:24 INFO - Collecting psutil>=3.1.1
02:36:25 INFO - Installing collected packages: psutil
02:36:25 INFO - Successfully installed psutil-3.1.1
02:36:25 INFO - Return code: 0
02:36:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
02:36:25 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:36:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:25 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:36:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
02:36:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
02:36:25 INFO - Using env: {'DISPLAY': ':0',
02:36:25 INFO - 'HOME': '/home/cltbld',
02:36:25 INFO - 'LANG': 'en_US.UTF-8',
02:36:25 INFO - 'LANGUAGE': 'en_US:en',
02:36:25 INFO - 'LOGNAME': 'cltbld',
02:36:25 INFO - 'MAIL': '/var/mail/cltbld',
02:36:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:25 INFO - 'MOZ_NO_REMOTE': '1',
02:36:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:25 INFO - 'NO_EM_RESTART': '1',
02:36:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:25 INFO - 'PWD': '/builds/slave/test',
02:36:25 INFO - 'SHELL': '/bin/bash',
02:36:25 INFO - 'SHLVL': '1',
02:36:25 INFO - 'TERM': 'linux',
02:36:25 INFO - 'TMOUT': '86400',
02:36:25 INFO - 'USER': 'cltbld',
02:36:25 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:25 INFO - '_': '/tools/buildbot/bin/python'}
02:36:25 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:25 INFO - Collecting mozsystemmonitor==0.3
02:36:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
02:36:26 INFO - Installing collected packages: mozsystemmonitor
02:36:26 INFO - Successfully installed mozsystemmonitor-0.3
02:36:26 INFO - Return code: 0
02:36:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
02:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
02:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
02:36:26 INFO - Using env: {'DISPLAY': ':0',
02:36:26 INFO - 'HOME': '/home/cltbld',
02:36:26 INFO - 'LANG': 'en_US.UTF-8',
02:36:26 INFO - 'LANGUAGE': 'en_US:en',
02:36:26 INFO - 'LOGNAME': 'cltbld',
02:36:26 INFO - 'MAIL': '/var/mail/cltbld',
02:36:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:26 INFO - 'MOZ_NO_REMOTE': '1',
02:36:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:26 INFO - 'NO_EM_RESTART': '1',
02:36:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:26 INFO - 'PWD': '/builds/slave/test',
02:36:26 INFO - 'SHELL': '/bin/bash',
02:36:26 INFO - 'SHLVL': '1',
02:36:26 INFO - 'TERM': 'linux',
02:36:26 INFO - 'TMOUT': '86400',
02:36:26 INFO - 'USER': 'cltbld',
02:36:26 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:26 INFO - '_': '/tools/buildbot/bin/python'}
02:36:27 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:27 INFO - Collecting jsonschema==2.5.1
02:36:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
02:36:28 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
02:36:29 INFO - Installing collected packages: functools32, jsonschema
02:36:29 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
02:36:29 INFO - Return code: 0
02:36:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
02:36: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:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36: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:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
02:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
02:36:29 INFO - Using env: {'DISPLAY': ':0',
02:36:29 INFO - 'HOME': '/home/cltbld',
02:36:29 INFO - 'LANG': 'en_US.UTF-8',
02:36:29 INFO - 'LANGUAGE': 'en_US:en',
02:36:29 INFO - 'LOGNAME': 'cltbld',
02:36:29 INFO - 'MAIL': '/var/mail/cltbld',
02:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:29 INFO - 'MOZ_NO_REMOTE': '1',
02:36:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:29 INFO - 'NO_EM_RESTART': '1',
02:36:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:29 INFO - 'PWD': '/builds/slave/test',
02:36:29 INFO - 'SHELL': '/bin/bash',
02:36:29 INFO - 'SHLVL': '1',
02:36:29 INFO - 'TERM': 'linux',
02:36:29 INFO - 'TMOUT': '86400',
02:36:29 INFO - 'USER': 'cltbld',
02:36:29 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:29 INFO - '_': '/tools/buildbot/bin/python'}
02:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
02:36:29 INFO - Return code: 0
02:36:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
02:36: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:36:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36: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:36:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
02:36:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
02:36:29 INFO - Using env: {'DISPLAY': ':0',
02:36:29 INFO - 'HOME': '/home/cltbld',
02:36:29 INFO - 'LANG': 'en_US.UTF-8',
02:36:29 INFO - 'LANGUAGE': 'en_US:en',
02:36:29 INFO - 'LOGNAME': 'cltbld',
02:36:29 INFO - 'MAIL': '/var/mail/cltbld',
02:36:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:29 INFO - 'MOZ_NO_REMOTE': '1',
02:36:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:29 INFO - 'NO_EM_RESTART': '1',
02:36:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:29 INFO - 'PWD': '/builds/slave/test',
02:36:29 INFO - 'SHELL': '/bin/bash',
02:36:29 INFO - 'SHLVL': '1',
02:36:29 INFO - 'TERM': 'linux',
02:36:29 INFO - 'TMOUT': '86400',
02:36:29 INFO - 'USER': 'cltbld',
02:36:29 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:29 INFO - '_': '/tools/buildbot/bin/python'}
02:36:30 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:30 INFO - Collecting blobuploader==1.2.4
02:36:31 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
02:36:32 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
02:36:33 INFO - Installing collected packages: requests, docopt, blobuploader
02:36:33 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
02:36:33 INFO - Return code: 0
02:36:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:36:33 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:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:33 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:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
02:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:36:33 INFO - Using env: {'DISPLAY': ':0',
02:36:33 INFO - 'HOME': '/home/cltbld',
02:36:33 INFO - 'LANG': 'en_US.UTF-8',
02:36:33 INFO - 'LANGUAGE': 'en_US:en',
02:36:33 INFO - 'LOGNAME': 'cltbld',
02:36:33 INFO - 'MAIL': '/var/mail/cltbld',
02:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:33 INFO - 'MOZ_NO_REMOTE': '1',
02:36:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:33 INFO - 'NO_EM_RESTART': '1',
02:36:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:33 INFO - 'PWD': '/builds/slave/test',
02:36:33 INFO - 'SHELL': '/bin/bash',
02:36:33 INFO - 'SHLVL': '1',
02:36:33 INFO - 'TERM': 'linux',
02:36:33 INFO - 'TMOUT': '86400',
02:36:33 INFO - 'USER': 'cltbld',
02:36:33 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:33 INFO - '_': '/tools/buildbot/bin/python'}
02:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:33 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:36:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:36:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:36:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:36:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:36:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:36:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:36:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:36:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:36:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:36:35 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:36:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:36:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:36:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:36:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:36:36 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:36:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:36:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:36:37 INFO - Running setup.py install for manifestparser: started
02:36:37 INFO - Running setup.py install for manifestparser: finished with status 'done'
02:36:37 INFO - Running setup.py install for mozcrash: started
02:36:37 INFO - Running setup.py install for mozcrash: finished with status 'done'
02:36:37 INFO - Running setup.py install for mozdebug: started
02:36:37 INFO - Running setup.py install for mozdebug: finished with status 'done'
02:36:37 INFO - Running setup.py install for mozdevice: started
02:36:38 INFO - Running setup.py install for mozdevice: finished with status 'done'
02:36:38 INFO - Running setup.py install for mozfile: started
02:36:38 INFO - Running setup.py install for mozfile: finished with status 'done'
02:36:38 INFO - Running setup.py install for mozhttpd: started
02:36:38 INFO - Running setup.py install for mozhttpd: finished with status 'done'
02:36:38 INFO - Running setup.py install for mozinfo: started
02:36:38 INFO - Running setup.py install for mozinfo: finished with status 'done'
02:36:38 INFO - Running setup.py install for mozInstall: started
02:36:38 INFO - Running setup.py install for mozInstall: finished with status 'done'
02:36:38 INFO - Running setup.py install for mozleak: started
02:36:39 INFO - Running setup.py install for mozleak: finished with status 'done'
02:36:39 INFO - Running setup.py install for mozlog: started
02:36:39 INFO - Running setup.py install for mozlog: finished with status 'done'
02:36:39 INFO - Running setup.py install for moznetwork: started
02:36:39 INFO - Running setup.py install for moznetwork: finished with status 'done'
02:36:39 INFO - Running setup.py install for mozprocess: started
02:36:39 INFO - Running setup.py install for mozprocess: finished with status 'done'
02:36:39 INFO - Running setup.py install for mozprofile: started
02:36:40 INFO - Running setup.py install for mozprofile: finished with status 'done'
02:36:40 INFO - Running setup.py install for mozrunner: started
02:36:40 INFO - Running setup.py install for mozrunner: finished with status 'done'
02:36:40 INFO - Running setup.py install for mozscreenshot: started
02:36:40 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
02:36:40 INFO - Running setup.py install for moztest: started
02:36:40 INFO - Running setup.py install for moztest: finished with status 'done'
02:36:40 INFO - Running setup.py install for mozversion: started
02:36:40 INFO - Running setup.py install for mozversion: finished with status 'done'
02:36:41 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
02:36:41 INFO - Return code: 0
02:36:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
02:36:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:36:41 INFO - Using env: {'DISPLAY': ':0',
02:36:41 INFO - 'HOME': '/home/cltbld',
02:36:41 INFO - 'LANG': 'en_US.UTF-8',
02:36:41 INFO - 'LANGUAGE': 'en_US:en',
02:36:41 INFO - 'LOGNAME': 'cltbld',
02:36:41 INFO - 'MAIL': '/var/mail/cltbld',
02:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:41 INFO - 'MOZ_NO_REMOTE': '1',
02:36:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:41 INFO - 'NO_EM_RESTART': '1',
02:36:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:41 INFO - 'PWD': '/builds/slave/test',
02:36:41 INFO - 'SHELL': '/bin/bash',
02:36:41 INFO - 'SHLVL': '1',
02:36:41 INFO - 'TERM': 'linux',
02:36:41 INFO - 'TMOUT': '86400',
02:36:41 INFO - 'USER': 'cltbld',
02:36:41 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:41 INFO - '_': '/tools/buildbot/bin/python'}
02:36:41 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:41 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:36:41 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:36:41 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:36:42 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:36:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:36:42 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:36:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:36:42 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:36:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:36:42 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:36:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:36:42 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:36:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:36:43 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:36:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:36:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
02:36:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:36:43 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:36:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:36:43 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:36:43 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:36:43 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:36:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:36:44 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:36:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:36:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
02:36:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:36:44 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:36:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:36:44 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:36:44 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:36:44 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:36:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:36:45 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:36:45 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:36:46 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
02:36:46 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:36:46 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:36:46 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:36:46 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:36:46 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:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
02:36:46 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:36:47 INFO - Installing collected packages: six, blessings
02:36:47 INFO - Successfully installed blessings-1.6 six-1.10.0
02:36:47 INFO - Return code: 0
02:36:47 INFO - Done creating virtualenv /builds/slave/test/build/venv.
02:36:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:36:47 INFO - Reading from file tmpfile_stdout
02:36:47 INFO - Reading from file tmpfile_stderr
02:36:47 INFO - Current package versions:
02:36:47 INFO - blessings == 1.6
02:36:47 INFO - blobuploader == 1.2.4
02:36:47 INFO - docopt == 0.6.1
02:36:47 INFO - functools32 == 3.2.3.post2
02:36:47 INFO - jsonschema == 2.5.1
02:36:47 INFO - manifestparser == 1.2
02:36:47 INFO - mozInstall == 1.14
02:36:47 INFO - mozcrash == 1.0
02:36:47 INFO - mozdebug == 0.1
02:36:47 INFO - mozdevice == 0.51
02:36:47 INFO - mozfile == 1.2
02:36:47 INFO - mozhttpd == 0.7
02:36:47 INFO - mozinfo == 0.10
02:36:47 INFO - mozleak == 0.1
02:36:47 INFO - mozlog == 3.5
02:36:47 INFO - moznetwork == 0.27
02:36:47 INFO - mozprocess == 0.25
02:36:47 INFO - mozprofile == 0.29
02:36:47 INFO - mozrunner == 6.13
02:36:47 INFO - mozscreenshot == 0.1
02:36:47 INFO - mozsystemmonitor == 0.3
02:36:47 INFO - moztest == 0.8
02:36:47 INFO - mozversion == 1.4
02:36:47 INFO - psutil == 3.1.1
02:36:47 INFO - requests == 1.2.3
02:36:47 INFO - six == 1.10.0
02:36:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
02:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:36:47 INFO - Using env: {'DISPLAY': ':0',
02:36:47 INFO - 'HOME': '/home/cltbld',
02:36:47 INFO - 'LANG': 'en_US.UTF-8',
02:36:47 INFO - 'LANGUAGE': 'en_US:en',
02:36:47 INFO - 'LOGNAME': 'cltbld',
02:36:47 INFO - 'MAIL': '/var/mail/cltbld',
02:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:47 INFO - 'MOZ_NO_REMOTE': '1',
02:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:47 INFO - 'NO_EM_RESTART': '1',
02:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:47 INFO - 'PWD': '/builds/slave/test',
02:36:47 INFO - 'SHELL': '/bin/bash',
02:36:47 INFO - 'SHLVL': '1',
02:36:47 INFO - 'TERM': 'linux',
02:36:47 INFO - 'TMOUT': '86400',
02:36:47 INFO - 'USER': 'cltbld',
02:36:47 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:48 INFO - '_': '/tools/buildbot/bin/python'}
02:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:48 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:36:48 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:36:48 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:36:48 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:36:48 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:36:48 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:36:48 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:36:48 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:36:48 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:36:48 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
02:36:49 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:36:50 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:36:50 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:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
02:36:50 INFO - Installing collected packages: simplejson, requests
02:36:50 INFO - Found existing installation: requests 1.2.3
02:36:50 INFO - Uninstalling requests-1.2.3:
02:36:50 INFO - Successfully uninstalled requests-1.2.3
02:36:51 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
02:36:51 INFO - Return code: 0
02:36:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
02:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:36:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:36:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:36:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf901f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x115aa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12fdcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667', '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:36:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
02:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
02:36:51 INFO - Using env: {'DISPLAY': ':0',
02:36:51 INFO - 'HOME': '/home/cltbld',
02:36:51 INFO - 'LANG': 'en_US.UTF-8',
02:36:51 INFO - 'LANGUAGE': 'en_US:en',
02:36:51 INFO - 'LOGNAME': 'cltbld',
02:36:51 INFO - 'MAIL': '/var/mail/cltbld',
02:36:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:36:51 INFO - 'MOZ_NO_REMOTE': '1',
02:36:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:36:51 INFO - 'NO_EM_RESTART': '1',
02:36:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:36:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:36:51 INFO - 'PWD': '/builds/slave/test',
02:36:51 INFO - 'SHELL': '/bin/bash',
02:36:51 INFO - 'SHLVL': '1',
02:36:51 INFO - 'TERM': 'linux',
02:36:51 INFO - 'TMOUT': '86400',
02:36:51 INFO - 'USER': 'cltbld',
02:36:51 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:36:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:36:51 INFO - '_': '/tools/buildbot/bin/python'}
02:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple
02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
02:36:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
02:36:51 INFO - Return code: 0
02:36:51 INFO - Running post-action listener: _resource_record_post_action
02:36:51 INFO - Running post-action listener: _start_resource_monitoring
02:36:51 INFO - Starting resource monitoring.
02:36:51 INFO - [mozharness: 2017-10-28 09:36:51.572106Z] Finished create-virtualenv step (success)
02:36:51 INFO - [mozharness: 2017-10-28 09:36:51.572619Z] Running install step.
02:36:51 INFO - Running pre-action listener: _resource_record_pre_action
02:36:51 INFO - Running main action method: install
02:36:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:36:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:36:52 INFO - Reading from file tmpfile_stdout
02:36:52 INFO - Reading from file tmpfile_stderr
02:36:52 INFO - Detecting whether we're running mozinstall >=1.0...
02:36:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
02:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
02:36:52 INFO - Reading from file tmpfile_stdout
02:36:52 INFO - Output received:
02:36:52 INFO - Usage: mozinstall [options] installer
02:36:52 INFO - Options:
02:36:52 INFO - -h, --help show this help message and exit
02:36:52 INFO - -d DEST, --destination=DEST
02:36:52 INFO - Directory to install application into. [default:
02:36:52 INFO - "/builds/slave/test"]
02:36:52 INFO - --app=APP Application being installed. [default: firefox]
02:36:52 INFO - mkdir: /builds/slave/test/build/application
02:36:52 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:36:52 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:10 INFO - Reading from file tmpfile_stdout
02:37:10 INFO - Output received:
02:37:10 INFO - /builds/slave/test/build/application/firefox/firefox
02:37:10 INFO - Running post-action listener: _resource_record_post_action
02:37:10 INFO - [mozharness: 2017-10-28 09:37:10.476129Z] Finished install step (success)
02:37:10 INFO - [mozharness: 2017-10-28 09:37:10.476378Z] Running setup-mitmproxy step.
02:37:10 INFO - Running pre-action listener: _resource_record_pre_action
02:37:10 INFO - Running main action method: setup_mitmproxy
02:37:10 INFO - Skipping: mitmproxy is not required
02:37:10 INFO - Running post-action listener: _resource_record_post_action
02:37:10 INFO - [mozharness: 2017-10-28 09:37:10.477490Z] Finished setup-mitmproxy step (success)
02:37:10 INFO - [mozharness: 2017-10-28 09:37:10.477709Z] Running run-tests step.
02:37:10 INFO - Running pre-action listener: _resource_record_pre_action
02:37:10 INFO - Running pre-action listener: _set_gcov_prefix
02:37:10 INFO - Running main action method: run_tests
02:37:10 WARNING - Try message not found.
02:37:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
02:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
02:37:10 INFO - Python 2.7.3
02:37:10 INFO - Return code: 0
02:37:10 INFO - grabbing minidump binary from tooltool
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 - 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 0x12fae60>, '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 0x12fbd60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12fc1f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
02:37:10 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:10 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:10 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:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
02:37:10 INFO - Return code: 0
02:37:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
02:37:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
02:37:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
02:37:10 INFO - ENV: RUST_BACKTRACE is now full
02:37:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
02:37:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
02:37:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
02:37:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-063 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
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 - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:37:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:37:10 INFO - 'MOZ_ACCELERATED': '1',
02:37:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:10 INFO - 'MOZ_NO_REMOTE': '1',
02:37:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:37:10 INFO - 'MOZ_WEBRENDER': '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': '/builds/slave/test/build/venv/bin:/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 - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:37:10 INFO - 'RUST_BACKTRACE': 'full',
02:37:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
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': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:37:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:10 INFO - '_': '/tools/buildbot/bin/python'}
02:37:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
02:37:10 INFO - mozversion application_buildid: 20171028083225
02:37:10 INFO - mozversion application_changeset: c16bc8097c102cdd2b70d8e7db3656222455a6ed
02:37:10 INFO - mozversion application_display_name: Nightly
02:37:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:37:10 INFO - mozversion application_name: Firefox
02:37:10 INFO - mozversion application_remotingname: firefox
02:37:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
02:37:10 INFO - mozversion application_vendor: Mozilla
02:37:10 INFO - mozversion application_version: 58.0a1
02:37:10 INFO - mozversion platform_buildid: 20171028083225
02:37:10 INFO - mozversion platform_changeset: c16bc8097c102cdd2b70d8e7db3656222455a6ed
02:37:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
02:37:10 INFO - mozversion platform_version: 58.0a1
02:37:10 INFO - using testdate: 1509183430
02:37:10 INFO - actual date: 1509183430
02:37:10 INFO - starting webserver on 'localhost:38011'
02:37:10 INFO - SUITE-START | Running 2 tests
02:37:10 INFO - TEST-START | basic_compositor_video
02:37:10 INFO - Initialising browser for basic_compositor_video test...
02:37:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38011/getInfo.html -profile /tmp/tmpBAS2I4/profile
02:37:10 INFO - TEST-INFO | started process 22089 (/builds/slave/test/build/application/firefox/firefox http://localhost:38011/getInfo.html)
02:37:18 INFO - TEST-INFO | 22089: exit 0
02:37:18 INFO - Browser initialized.
02:37:18 INFO - Running cycle 1/1 for basic_compositor_video test...
02:37:18 INFO - Using env: {'DISPLAY': ':0',
02:37:18 INFO - 'HOME': '/home/cltbld',
02:37:18 INFO - 'JSGC_DISABLE_POISONING': '1',
02:37:18 INFO - 'LANG': 'en_US.UTF-8',
02:37:18 INFO - 'LANGUAGE': 'en_US:en',
02:37:18 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:37:18 INFO - 'LOGNAME': 'cltbld',
02:37:18 INFO - 'MAIL': '/var/mail/cltbld',
02:37:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:37:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:37:18 INFO - 'MOZ_ACCELERATED': '1',
02:37:18 INFO - 'MOZ_CRASHREPORTER': '1',
02:37:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:37:18 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:37:18 INFO - 'MOZ_NO_REMOTE': '1',
02:37:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:37:18 INFO - 'MOZ_WEBRENDER': '1',
02:37:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:37:18 INFO - 'NO_EM_RESTART': '1',
02:37:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:37:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:37:18 INFO - 'PWD': '/builds/slave/test',
02:37:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:37:18 INFO - 'RUST_BACKTRACE': 'full',
02:37:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:37:18 INFO - 'SHELL': '/bin/bash',
02:37:18 INFO - 'SHLVL': '1',
02:37:18 INFO - 'TERM': 'linux',
02:37:18 INFO - 'TMOUT': '86400',
02:37:18 INFO - 'USER': 'cltbld',
02:37:18 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:37:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:37:18 INFO - '_': '/tools/buildbot/bin/python'}
02:37:18 INFO - TEST-INFO | started process 22332 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBAS2I4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
02:37:19 INFO - PID 22332 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:37:19 INFO - PID 22332 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:37:19 INFO - PID 22332 |
02:37:19 INFO - PID 22332 | (/builds/slave/test/build/application/firefox/firefox:22394): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:37:19 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.922093476144109 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5819096774193553 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.657342781222321 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.631874999999999 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.219785407725322 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.190287081339712 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.323890489913543 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.256265957446816 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.380058394160584 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.378380743982499 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.777643312101912 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.764011904761901 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.266138014527838 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.017368421052647 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.212656250000009 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.018771929824556 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.299574209245742 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.046073943661969 ms/frame
02:38:05 INFO - PID 22332 |
02:38:05 INFO - PID 22332 | Cycle 1(1): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:38:51 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.615880557977332 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5878848641655887 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6437533039647576 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6320723684210527 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2086844919786084 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.1961102236421746 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.658889751552796 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.663111888111885 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.505803303303305 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.515011286681711 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.8169662921348335 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.784497607655498 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.2817354368932055 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.042499999999999 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.282038834951467 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.9936188811188655 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.283191747572823 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.071908127208475 ms/frame
02:38:51 INFO - PID 22332 |
02:38:51 INFO - PID 22332 | Cycle 1(2): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:39:37 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6089652173913045 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.595337224383917 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.661943212067436 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6283837056504584 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2016755602988254 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.1963338658146974 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.615492307692307 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.4455000000000044 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.504887387387389 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.567842465753425 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.902401960784315 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.855157766990293 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.3007664233576595 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.045404929577479 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.282148058252433 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.043362676056346 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.318451219512201 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.0675441696113 ms/frame
02:39:37 INFO - PID 22332 |
02:39:37 INFO - PID 22332 | Cycle 1(3): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:40:23 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6019601040763227 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5814193548387103 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.645855379188713 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6257480314960637 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.20511217948718 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.195974440894569 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.688765624999999 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.599275862068969 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.399054252199412 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.330129870129871 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.919016393442626 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.856796116504855 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.265484261501212 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.068551236749122 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.319243902439023 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.093226950354613 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.266029055690061 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.023263157894753 ms/frame
02:40:23 INFO - PID 22332 |
02:40:23 INFO - PID 22332 | Cycle 1(4): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:41:09 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6118798955613576 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.591677461139897 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6509628975265023 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.635046113306983 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.226301075268818 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.200103999999998 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.418630338733429 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.310398706896555 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.609001536098309 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.597931034482763 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.9026470588235265 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.854757281553398 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.301119221411188 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.069028268551249 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.230168674698805 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.993548951048947 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.299416058394155 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.066978798586579 ms/frame
02:41:09 INFO - PID 22332 |
02:41:09 INFO - PID 22332 | Cycle 1(5): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:41:55 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.597822510822511 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.594195849546044 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.636300527240774 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6289881734559777 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.21980686695279 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2064342948717957 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.262379261363637 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.247399150743095 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.419175257731958 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.310258620689654 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.815834670947029 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.7973621103117505 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.231216867469875 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.04294014084507 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.246461352657013 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.021140350877198 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.3178292682926775 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.019701754385958 ms/frame
02:41:55 INFO - PID 22332 |
02:41:55 INFO - PID 22332 | Cycle 1(6): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:42:20 INFO - PID 22332 |
02:42:20 INFO - PID 22332 | (/builds/slave/test/build/application/firefox/firefox:22475): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:42:20 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.590975820379966 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.581606451612904 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6409330985915496 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6322368421052618 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2016275346851666 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.200343999999997 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.6228043143297395 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.505945945945944 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.505195195195194 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.397230769230773 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.816195826645267 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.751496437054626 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.2647094430992665 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.119359430604986 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.300863746958652 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.045228873239415 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.246666666666673 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.0691696113074105 ms/frame
02:42:41 INFO - PID 22332 |
02:42:41 INFO - PID 22332 | Cycle 1(7): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:43:27 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.5933189282627485 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.597487012987013 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6438017621145367 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6357839262187093 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.223415682062299 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2012079999999985 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.250318696883853 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.219556962025316 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.471795827123697 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.376345733041571 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.878349593495932 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.878731707317079 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.281881067961161 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.042535211267611 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.318609756097555 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.067243816254413 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.264769975786922 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.969912891986081 ms/frame
02:43:27 INFO - PID 22332 |
02:43:27 INFO - PID 22332 | Cycle 1(8): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:44:13 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.595622837370242 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5786920103092776 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.639120492524186 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6357509881422936 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.206030982905982 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.195103833865815 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.471304023845009 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.454910913140314 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.342402315484808 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.321101511879046 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.878260162601627 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.8443825665859555 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.318256097560977 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.019122807017534 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.195467625899285 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.995489510489508 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.3192560975609675 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.020105263157906 ms/frame
02:44:13 INFO - PID 22332 |
02:44:13 INFO - PID 22332 | Cycle 1(9): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:44:59 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6295223488168276 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.595466926070039 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.645679012345679 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.632769736842105 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2017289220917826 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.1952156549520767 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.602645705521471 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.685526932084312 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.398885630498535 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.338600867678961 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.800680000000004 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.7846052631578955 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.336748166259167 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.068127208480561 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.229578313253016 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.969564459930312 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.196906474820145 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.018333333333328 ms/frame
02:44:59 INFO - PID 22332 |
02:44:59 INFO - PID 22332 | Cycle 1(10): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:45:45 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6090478260869565 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5880853816300133 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.654933628318584 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6251181102362193 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.208754010695187 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2002959999999994 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.287014285714288 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.220685654008438 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.380481751824814 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.302763440860215 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.8079807692307694 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.750641330166272 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.282293689320389 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.068339222614829 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.211526442307699 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.99569930069929 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.2835072815533985 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.042605633802812 ms/frame
02:45:45 INFO - PID 22332 |
02:45:45 INFO - PID 22332 | Cycle 1(11): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:46:31 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_startup = 2.606642050390964 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5981753246753243 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.662125110913931 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6322565789473686 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_startup = 3.215819935691317 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2010560000000026 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_startup = 4.225971830985913 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1_inclip = 4.185481171548117 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.3356647398843915 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.293465665236054 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_startup = 4.832270531400966 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.480p.60fps.webm_scale_2_inclip = 4.764083333333327 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.283567961165053 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.044489436619726 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.319707317073157 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.018684210526331 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.301265206812651 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.018964912280698 ms/frame
02:46:31 INFO - PID 22332 |
02:46:31 INFO - PID 22332 | Cycle 1(12): loaded http://localhost:38011/tests/video/video_playback.html (next: http://localhost:38011/tests/video/video_playback.html)
02:46:32 INFO - PID 22332 | __start_tp_report
02:46:32 INFO - PID 22332 | _x_x_mozilla_page_load
02:46:32 INFO - PID 22332 | _x_x_mozilla_page_load_details
02:46:32 INFO - PID 22332 | |i|pagename|runs|
02:46:32 INFO - PID 22332 | |0;240p.120fps.mp4_scale_1_startup;2.922093476144109;2.615880557977332;2.6089652173913045;2.6019601040763227;2.6118798955613576;2.597822510822511;2.590975820379966;2.5933189282627485;2.595622837370242;2.6295223488168276;2.6090478260869565;2.606642050390964
02:46:32 INFO - PID 22332 | |1;240p.120fps.mp4_scale_1_inclip;2.5819096774193553;2.5878848641655887;2.595337224383917;2.5814193548387103;2.591677461139897;2.594195849546044;2.581606451612904;2.597487012987013;2.5786920103092776;2.595466926070039;2.5880853816300133;2.5981753246753243
02:46:32 INFO - PID 22332 | |2;240p.120fps.mp4_scale_1.1_startup;2.657342781222321;2.6437533039647576;2.661943212067436;2.645855379188713;2.6509628975265023;2.636300527240774;2.6409330985915496;2.6438017621145367;2.639120492524186;2.645679012345679;2.654933628318584;2.662125110913931
02:46:32 INFO - PID 22332 | |3;240p.120fps.mp4_scale_1.1_inclip;2.631874999999999;2.6320723684210527;2.6283837056504584;2.6257480314960637;2.635046113306983;2.6289881734559777;2.6322368421052618;2.6357839262187093;2.6357509881422936;2.632769736842105;2.6251181102362193;2.6322565789473686
02:46:32 INFO - PID 22332 | |4;240p.120fps.mp4_scale_2_startup;3.219785407725322;3.2086844919786084;3.2016755602988254;3.20511217948718;3.226301075268818;3.21980686695279;3.2016275346851666;3.223415682062299;3.206030982905982;3.2017289220917826;3.208754010695187;3.215819935691317
02:46:32 INFO - PID 22332 | |5;240p.120fps.mp4_scale_2_inclip;3.190287081339712;3.1961102236421746;3.1963338658146974;3.195974440894569;3.200103999999998;3.2064342948717957;3.200343999999997;3.2012079999999985;3.195103833865815;3.1952156549520767;3.2002959999999994;3.2010560000000026
02:46:32 INFO - PID 22332 | |6;480p.60fps.webm_scale_1_startup;4.323890489913543;4.658889751552796;4.615492307692307;4.688765624999999;4.418630338733429;4.262379261363637;4.6228043143297395;4.250318696883853;4.471304023845009;4.602645705521471;4.287014285714288;4.225971830985913
02:46:32 INFO - PID 22332 | |7;480p.60fps.webm_scale_1_inclip;4.256265957446816;4.663111888111885;4.4455000000000044;4.599275862068969;4.310398706896555;4.247399150743095;4.505945945945944;4.219556962025316;4.454910913140314;4.685526932084312;4.220685654008438;4.185481171548117
02:46:32 INFO - PID 22332 | |8;480p.60fps.webm_scale_1.1_startup;4.380058394160584;4.505803303303305;4.504887387387389;4.399054252199412;4.609001536098309;4.419175257731958;4.505195195195194;4.471795827123697;4.342402315484808;4.398885630498535;4.380481751824814;4.3356647398843915
02:46:32 INFO - PID 22332 | |9;480p.60fps.webm_scale_1.1_inclip;4.378380743982499;4.515011286681711;4.567842465753425;4.330129870129871;4.597931034482763;4.310258620689654;4.397230769230773;4.376345733041571;4.321101511879046;4.338600867678961;4.302763440860215;4.293465665236054
02:46:32 INFO - PID 22332 | |10;480p.60fps.webm_scale_2_startup;4.777643312101912;4.8169662921348335;4.902401960784315;4.919016393442626;4.9026470588235265;4.815834670947029;4.816195826645267;4.878349593495932;4.878260162601627;4.800680000000004;4.8079807692307694;4.832270531400966
02:46:32 INFO - PID 22332 | |11;480p.60fps.webm_scale_2_inclip;4.764011904761901;4.784497607655498;4.855157766990293;4.856796116504855;4.854757281553398;4.7973621103117505;4.751496437054626;4.878731707317079;4.8443825665859555;4.7846052631578955;4.750641330166272;4.764083333333327
02:46:32 INFO - PID 22332 | |12;1080p.60fps.mp4_scale_1_startup;7.266138014527838;7.2817354368932055;7.3007664233576595;7.265484261501212;7.301119221411188;7.231216867469875;7.2647094430992665;7.281881067961161;7.318256097560977;7.336748166259167;7.282293689320389;7.283567961165053
02:46:32 INFO - PID 22332 | |13;1080p.60fps.mp4_scale_1_inclip;7.017368421052647;7.042499999999999;7.045404929577479;7.068551236749122;7.069028268551249;7.04294014084507;7.119359430604986;7.042535211267611;7.019122807017534;7.068127208480561;7.068339222614829;7.044489436619726
02:46:32 INFO - PID 22332 | |14;1080p.60fps.mp4_scale_1.1_startup;7.212656250000009;7.282038834951467;7.282148058252433;7.319243902439023;7.230168674698805;7.246461352657013;7.300863746958652;7.318609756097555;7.195467625899285;7.229578313253016;7.211526442307699;7.319707317073157
02:46:32 INFO - PID 22332 | |15;1080p.60fps.mp4_scale_1.1_inclip;7.018771929824556;6.9936188811188655;7.043362676056346;7.093226950354613;6.993548951048947;7.021140350877198;7.045228873239415;7.067243816254413;6.995489510489508;6.969564459930312;6.99569930069929;7.018684210526331
02:46:32 INFO - PID 22332 | |16;1080p.60fps.mp4_scale_2_startup;7.299574209245742;7.283191747572823;7.318451219512201;7.266029055690061;7.299416058394155;7.3178292682926775;7.246666666666673;7.264769975786922;7.3192560975609675;7.196906474820145;7.2835072815533985;7.301265206812651
02:46:32 INFO - PID 22332 | |17;1080p.60fps.mp4_scale_2_inclip;7.046073943661969;7.071908127208475;7.0675441696113;7.023263157894753;7.066978798586579;7.019701754385958;7.0691696113074105;6.969912891986081;7.020105263157906;7.018333333333328;7.042605633802812;7.018964912280698
02:46:32 INFO - PID 22332 | __end_tp_report
02:46:32 INFO - PID 22332 | __start_cc_report
02:46:32 INFO - PID 22332 | _x_x_mozilla_cycle_collect,597
02:46:32 INFO - PID 22332 | __end_cc_report
02:46:32 INFO - PID 22332 | __startTimestamp1509183992104__endTimestamp
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | ------- Summary: start -------
02:46:32 INFO - PID 22332 | Number of tests: 18
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.63 Median:2.61 stddev:0.09 (3.5%) stddev-sans-first:0.01
02:46:32 INFO - PID 22332 | Values: 2.9 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.59 Median:2.59 stddev:0.01 (0.3%) stddev-sans-first:0.01
02:46:32 INFO - PID 22332 | Values: 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.65 Median:2.65 stddev:0.01 (0.3%) stddev-sans-first:0.01
02:46:32 INFO - PID 22332 | Values: 2.7 2.6 2.7 2.6 2.7 2.6 2.6 2.6 2.6 2.6 2.7 2.7
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.63 Median:2.63 stddev:0.00 (0.1%) stddev-sans-first:0.00
02:46:32 INFO - PID 22332 | Values: 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.21 Median:3.21 stddev:0.01 (0.3%) stddev-sans-first:0.01
02:46:32 INFO - PID 22332 | Values: 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.20 Median:3.20 stddev:0.00 (0.1%) stddev-sans-first:0.00
02:46:32 INFO - PID 22332 | Values: 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.45 Median:4.54 stddev:0.18 (3.9%) stddev-sans-first:0.18
02:46:32 INFO - PID 22332 | Values: 4.3 4.7 4.6 4.7 4.4 4.3 4.6 4.3 4.5 4.6 4.3 4.2
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.40 Median:4.45 stddev:0.18 (4.1%) stddev-sans-first:0.19
02:46:32 INFO - PID 22332 | Values: 4.3 4.7 4.4 4.6 4.3 4.2 4.5 4.2 4.5 4.7 4.2 4.2
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.44 Median:4.45 stddev:0.08 (1.8%) stddev-sans-first:0.08
02:46:32 INFO - PID 22332 | Values: 4.4 4.5 4.5 4.4 4.6 4.4 4.5 4.5 4.3 4.4 4.4 4.3
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.39 Median:4.38 stddev:0.11 (2.4%) stddev-sans-first:0.11
02:46:32 INFO - PID 22332 | Values: 4.4 4.5 4.6 4.3 4.6 4.3 4.4 4.4 4.3 4.3 4.3 4.3
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.85 Median:4.86 stddev:0.05 (1.0%) stddev-sans-first:0.04
02:46:32 INFO - PID 22332 | Values: 4.8 4.8 4.9 4.9 4.9 4.8 4.8 4.9 4.9 4.8 4.8 4.8
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.81 Median:4.82 stddev:0.05 (1.0%) stddev-sans-first:0.05
02:46:32 INFO - PID 22332 | Values: 4.8 4.8 4.9 4.9 4.9 4.8 4.8 4.9 4.8 4.8 4.8 4.8
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.28 Median:7.28 stddev:0.03 (0.4%) stddev-sans-first:0.03
02:46:32 INFO - PID 22332 | Values: 7.3 7.3 7.3 7.3 7.3 7.2 7.3 7.3 7.3 7.3 7.3 7.3
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:7.05 Median:7.06 stddev:0.03 (0.4%) stddev-sans-first:0.03
02:46:32 INFO - PID 22332 | Values: 7.0 7.0 7.0 7.1 7.1 7.0 7.1 7.0 7.0 7.1 7.1 7.0
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.26 Median:7.28 stddev:0.05 (0.6%) stddev-sans-first:0.05
02:46:32 INFO - PID 22332 | Values: 7.2 7.3 7.3 7.3 7.2 7.2 7.3 7.3 7.2 7.2 7.2 7.3
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:7.02 Median:7.02 stddev:0.04 (0.5%) stddev-sans-first:0.04
02:46:32 INFO - PID 22332 | Values: 7.0 7.0 7.0 7.1 7.0 7.0 7.0 7.1 7.0 7.0 7.0 7.0
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.28 Median:7.30 stddev:0.04 (0.5%) stddev-sans-first:0.04
02:46:32 INFO - PID 22332 | Values: 7.3 7.3 7.3 7.3 7.3 7.3 7.2 7.3 7.3 7.2 7.3 7.3
02:46:32 INFO - PID 22332 |
02:46:32 INFO - PID 22332 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:7.04 Median:7.04 stddev:0.03 (0.4%) stddev-sans-first:0.03
02:46:32 INFO - PID 22332 | Values: 7.0 7.1 7.1 7.0 7.1 7.0 7.1 7.0 7.0 7.0 7.0 7.0
02:46:32 INFO - PID 22332 | -------- Summary: end --------
02:46:32 INFO - PID 22332 |
02:46:32 INFO - TEST-INFO | 22332: exit 0
02:46:32 INFO - TEST-OK | basic_compositor_video | took 561928ms
02:46:32 INFO - TEST-START | glvideo
02:46:32 INFO - Initialising browser for glvideo test...
02:46:32 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:38011/getInfo.html -profile /tmp/tmpYiwfnZ/profile
02:46:32 INFO - TEST-INFO | started process 23649 (/builds/slave/test/build/application/firefox/firefox http://localhost:38011/getInfo.html)
02:46:38 INFO - TEST-INFO | 23649: exit 0
02:46:38 INFO - Browser initialized.
02:46:38 INFO - Running cycle 1/1 for glvideo test...
02:46:38 INFO - Using env: {'DISPLAY': ':0',
02:46:38 INFO - 'HOME': '/home/cltbld',
02:46:38 INFO - 'JSGC_DISABLE_POISONING': '1',
02:46:38 INFO - 'LANG': 'en_US.UTF-8',
02:46:38 INFO - 'LANGUAGE': 'en_US:en',
02:46:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:46:38 INFO - 'LOGNAME': 'cltbld',
02:46:38 INFO - 'MAIL': '/var/mail/cltbld',
02:46:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:46:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:46:38 INFO - 'MOZ_ACCELERATED': '1',
02:46:38 INFO - 'MOZ_CRASHREPORTER': '1',
02:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:46:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:46:38 INFO - 'MOZ_NO_REMOTE': '1',
02:46:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:46:38 INFO - 'MOZ_WEBRENDER': '1',
02:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:46:38 INFO - 'NO_EM_RESTART': '1',
02:46:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:46:38 INFO - 'PWD': '/builds/slave/test',
02:46:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:46:38 INFO - 'RUST_BACKTRACE': 'full',
02:46:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:46:38 INFO - 'SHELL': '/bin/bash',
02:46:38 INFO - 'SHLVL': '1',
02:46:38 INFO - 'TERM': 'linux',
02:46:38 INFO - 'TMOUT': '86400',
02:46:38 INFO - 'USER': 'cltbld',
02:46:38 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667',
02:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:46:38 INFO - '_': '/tools/buildbot/bin/python'}
02:46:38 INFO - TEST-INFO | started process 23934 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYiwfnZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
02:46:39 INFO - PID 23934 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:46:39 INFO - PID 23934 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:46:39 INFO - PID 23934 |
02:46:39 INFO - PID 23934 | (/builds/slave/test/build/application/firefox/firefox:23995): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:46:39 INFO - PID 23934 |
02:46:41 INFO - PID 23934 | [talos glvideo result] Mean tick time across 100 ticks: 6.7907 ms
02:46:41 INFO - PID 23934 | Cycle 1(1): loaded http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html)
02:46:42 INFO - PID 23934 | [talos glvideo result] Mean tick time across 100 ticks: 6.57125 ms
02:46:42 INFO - PID 23934 | Cycle 1(2): loaded http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html)
02:46:44 INFO - PID 23934 | [talos glvideo result] Mean tick time across 100 ticks: 6.5692 ms
02:46:44 INFO - PID 23934 | Cycle 1(3): loaded http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html)
02:46:45 INFO - PID 23934 | [talos glvideo result] Mean tick time across 100 ticks: 6.570400000000001 ms
02:46:45 INFO - PID 23934 | Cycle 1(4): loaded http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html)
02:46:46 INFO - PID 23934 | [talos glvideo result] Mean tick time across 100 ticks: 6.51815 ms
02:46:46 INFO - PID 23934 | Cycle 1(5): loaded http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:38011/tests/webgl/benchmarks/video/video_upload.html)
02:46:46 INFO - PID 23934 | __start_tp_report
02:46:46 INFO - PID 23934 | _x_x_mozilla_page_load
02:46:46 INFO - PID 23934 | _x_x_mozilla_page_load_details
02:46:46 INFO - PID 23934 | |i|pagename|runs|
02:46:46 INFO - PID 23934 | |0;Mean tick time across 100 ticks: ;6.7907;6.57125;6.5692;6.570400000000001;6.51815
02:46:46 INFO - PID 23934 | __end_tp_report
02:46:46 INFO - PID 23934 | __start_cc_report
02:46:46 INFO - PID 23934 | _x_x_mozilla_cycle_collect,280
02:46:46 INFO - PID 23934 | __end_cc_report
02:46:46 INFO - PID 23934 | __startTimestamp1509184006986__endTimestamp
02:46:46 INFO - PID 23934 |
02:46:46 INFO - PID 23934 | ------- Summary: start -------
02:46:46 INFO - PID 23934 | Number of tests: 1
02:46:46 INFO - PID 23934 |
02:46:46 INFO - PID 23934 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.60 Median:6.57 stddev:0.11 (1.6%) stddev-sans-first:0.03
02:46:46 INFO - PID 23934 | Values: 6.8 6.6 6.6 6.6 6.5
02:46:46 INFO - PID 23934 | -------- Summary: end --------
02:46:46 INFO - PID 23934 |
02:46:47 INFO - PID 23934 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
02:46:47 INFO - TEST-INFO | 23934: exit 0
02:46:47 INFO - TEST-OK | glvideo | took 14936ms
02:46:47 INFO - SUITE-END | took 576s
02:46:48 INFO - Completed test suite (00:09:37)
02:46:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.562471886734209, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.922093476144109, 2.615880557977332, 2.6089652173913045, 2.6019601040763227, 2.6118798955613576, 2.597822510822511, 2.590975820379966, 2.5933189282627485, 2.595622837370242, 2.6295223488168276, 2.6090478260869565, 2.606642050390964], "value": 2.606642050390964, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.5819096774193553, 2.5878848641655887, 2.595337224383917, 2.5814193548387103, 2.591677461139897, 2.594195849546044, 2.581606451612904, 2.597487012987013, 2.5786920103092776, 2.595466926070039, 2.5880853816300133, 2.5981753246753243], "value": 2.591677461139897, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.657342781222321, 2.6437533039647576, 2.661943212067436, 2.645855379188713, 2.6509628975265023, 2.636300527240774, 2.6409330985915496, 2.6438017621145367, 2.639120492524186, 2.645679012345679, 2.654933628318584, 2.662125110913931], "value": 2.645679012345679, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.631874999999999, 2.6320723684210527, 2.6283837056504584, 2.6257480314960637, 2.635046113306983, 2.6289881734559777, 2.6322368421052618, 2.6357839262187093, 2.6357509881422936, 2.632769736842105, 2.6251181102362193, 2.6322565789473686], "value": 2.6322368421052618, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.219785407725322, 3.2086844919786084, 3.2016755602988254, 3.20511217948718, 3.226301075268818, 3.21980686695279, 3.2016275346851666, 3.223415682062299, 3.206030982905982, 3.2017289220917826, 3.208754010695187, 3.215819935691317], "value": 3.2086844919786084, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.190287081339712, 3.1961102236421746, 3.1963338658146974, 3.195974440894569, 3.200103999999998, 3.2064342948717957, 3.200343999999997, 3.2012079999999985, 3.195103833865815, 3.1952156549520767, 3.2002959999999994, 3.2010560000000026], "value": 3.200103999999998, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.323890489913543, 4.658889751552796, 4.615492307692307, 4.688765624999999, 4.418630338733429, 4.262379261363637, 4.6228043143297395, 4.250318696883853, 4.471304023845009, 4.602645705521471, 4.287014285714288, 4.225971830985913], "value": 4.471304023845009, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.256265957446816, 4.663111888111885, 4.4455000000000044, 4.599275862068969, 4.310398706896555, 4.247399150743095, 4.505945945945944, 4.219556962025316, 4.454910913140314, 4.685526932084312, 4.220685654008438, 4.185481171548117], "value": 4.4455000000000044, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.380058394160584, 4.505803303303305, 4.504887387387389, 4.399054252199412, 4.609001536098309, 4.419175257731958, 4.505195195195194, 4.471795827123697, 4.342402315484808, 4.398885630498535, 4.380481751824814, 4.3356647398843915], "value": 4.419175257731958, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.378380743982499, 4.515011286681711, 4.567842465753425, 4.330129870129871, 4.597931034482763, 4.310258620689654, 4.397230769230773, 4.376345733041571, 4.321101511879046, 4.338600867678961, 4.302763440860215, 4.293465665236054], "value": 4.338600867678961, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.777643312101912, 4.8169662921348335, 4.902401960784315, 4.919016393442626, 4.9026470588235265, 4.815834670947029, 4.816195826645267, 4.878349593495932, 4.878260162601627, 4.800680000000004, 4.8079807692307694, 4.832270531400966], "value": 4.832270531400966, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.764011904761901, 4.784497607655498, 4.855157766990293, 4.856796116504855, 4.854757281553398, 4.7973621103117505, 4.751496437054626, 4.878731707317079, 4.8443825665859555, 4.7846052631578955, 4.750641330166272, 4.764083333333327], "value": 4.7973621103117505, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.266138014527838, 7.2817354368932055, 7.3007664233576595, 7.265484261501212, 7.301119221411188, 7.231216867469875, 7.2647094430992665, 7.281881067961161, 7.318256097560977, 7.336748166259167, 7.282293689320389, 7.283567961165053], "value": 7.282293689320389, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.017368421052647, 7.042499999999999, 7.045404929577479, 7.068551236749122, 7.069028268551249, 7.04294014084507, 7.119359430604986, 7.042535211267611, 7.019122807017534, 7.068127208480561, 7.068339222614829, 7.044489436619726], "value": 7.045404929577479, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.212656250000009, 7.282038834951467, 7.282148058252433, 7.319243902439023, 7.230168674698805, 7.246461352657013, 7.300863746958652, 7.318609756097555, 7.195467625899285, 7.229578313253016, 7.211526442307699, 7.319707317073157], "value": 7.282038834951467, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.018771929824556, 6.9936188811188655, 7.043362676056346, 7.093226950354613, 6.993548951048947, 7.021140350877198, 7.045228873239415, 7.067243816254413, 6.995489510489508, 6.969564459930312, 6.99569930069929, 7.018684210526331], "value": 7.018684210526331, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.299574209245742, 7.283191747572823, 7.318451219512201, 7.266029055690061, 7.299416058394155, 7.3178292682926775, 7.246666666666673, 7.264769975786922, 7.3192560975609675, 7.196906474820145, 7.2835072815533985, 7.301265206812651], "value": 7.2835072815533985, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.046073943661969, 7.071908127208475, 7.0675441696113, 7.023263157894753, 7.066978798586579, 7.019701754385958, 7.0691696113074105, 6.969912891986081, 7.020105263157906, 7.018333333333328, 7.042605633802812, 7.018964912280698], "value": 7.023263157894753, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.7907, 6.57125, 6.5692, 6.570400000000001, 6.51815], "value": 6.569800000000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}]}
02:46:48 INFO - Return code: 0
02:46:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
02:46:48 INFO - # TBPL SUCCESS #
02:46:48 INFO - Running post-action listener: _package_coverage_data
02:46:48 INFO - Running post-action listener: _resource_record_post_action
02:46:48 INFO - [mozharness: 2017-10-28 09:46:48.130817Z] Finished run-tests step (success)
02:46:48 INFO - Running post-run listener: _resource_record_post_run
02:46:48 INFO - Total resource usage - Wall time: 596s; CPU: 46.0%; Read bytes: 10870784; Write bytes: 447303680; Read time: 5548; Write time: 368860
02:46:48 INFO - TinderboxPrint: CPU usage
46.0%
02:46:48 INFO - TinderboxPrint: I/O read bytes / time
10,870,784 / 5,548
02:46:48 INFO - TinderboxPrint: I/O write bytes / time
447,303,680 / 368,860
02:46:48 INFO - TinderboxPrint: CPU idle
2,402.6 (53.9%)
02:46:48 INFO - TinderboxPrint: CPU system
634.5 (14.2%)
02:46:48 INFO - TinderboxPrint: CPU user
1,397.8 (31.4%)
02:46:48 INFO - TinderboxPrint: Swap in / out
0 / 0
02:46:48 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 8192; Write bytes: 136306688; Read time: 24; Write time: 130820
02:46:48 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
02:46:48 INFO - run-tests - Wall time: 578s; CPU: 47.0%; Read bytes: 10862592; Write bytes: 310996992; Read time: 5524; Write time: 238040
02:46:48 INFO - Running post-run listener: _upload_blobber_files
02:46:48 INFO - Blob upload gear active.
02:46:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
02:46:48 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:46:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
02:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
02:46:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:334: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:48 INFO - SNIMissingWarning
02:46:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:48 INFO - InsecurePlatformWarning
02:46:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
02:46:48 INFO - SubjectAltNameWarning
02:46:48 INFO - (blobuploader) - INFO - Open directory for files ...
02:46:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
02:46:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:46:48 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:46:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:48 INFO - InsecurePlatformWarning
02:46:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
02:46:49 INFO - SubjectAltNameWarning
02:46:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:49 INFO - InsecurePlatformWarning
02:46:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
02:46:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:46:49 INFO - (blobuploader) - INFO - Done attempting.
02:46:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
02:46:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:46:49 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:46:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:49 INFO - InsecurePlatformWarning
02:46:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
02:46:50 INFO - SubjectAltNameWarning
02:46:52 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:52 INFO - InsecurePlatformWarning
02:46:52 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
02:46:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:46:52 INFO - (blobuploader) - INFO - Done attempting.
02:46:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
02:46:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:46:52 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:46:52 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:52 INFO - InsecurePlatformWarning
02:46:53 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
02:46:53 INFO - SubjectAltNameWarning
02:46:53 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:53 INFO - InsecurePlatformWarning
02:46:53 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
02:46:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:46:53 INFO - (blobuploader) - INFO - Done attempting.
02:46:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
02:46:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:46:53 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:46:53 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:53 INFO - InsecurePlatformWarning
02:46:53 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
02:46:53 INFO - SubjectAltNameWarning
02:46:58 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
02:46:58 INFO - InsecurePlatformWarning
02:46:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
02:46:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:46:58 INFO - (blobuploader) - INFO - Done attempting.
02:46:58 INFO - (blobuploader) - INFO - Iteration through files over.
02:46:58 INFO - Return code: 0
02:46:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
02:46:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
02:46:58 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e471460c51c39b72f58aab83050511669d6a35be03c1de4e4acea59585113946f04f5ebadaf9a7c716c2cd30f29b69d66cc0b18f9aacf896ffd66d27b2df62", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfcc463d5ca92d6521ba9ff2d45495dd3d511375576063d9dc50efd4bb20e53de92712697dfb8eb722fe09a03ad2b735afaf433fd04c60abc88534be034aecf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62633f4315516a454ebb1665956d9902e386b34961322b285008f782ab44fc7d9627d8b72dfa67dd8f8707e724595818bf7e11cb92ec8880633e969fca59a44", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3905f990a55c1ddbd0de56406536faf91e13f8f41743587a9bd15971d1e08d9496ef1467948c863257520f499b37695888a6f2bd786ffb73289ca1d049aa4209"}
02:46:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
02:46:58 INFO - Writing to file /builds/slave/test/properties/blobber_files
02:46:58 INFO - Contents:
02:46:58 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e471460c51c39b72f58aab83050511669d6a35be03c1de4e4acea59585113946f04f5ebadaf9a7c716c2cd30f29b69d66cc0b18f9aacf896ffd66d27b2df62", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfcc463d5ca92d6521ba9ff2d45495dd3d511375576063d9dc50efd4bb20e53de92712697dfb8eb722fe09a03ad2b735afaf433fd04c60abc88534be034aecf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62633f4315516a454ebb1665956d9902e386b34961322b285008f782ab44fc7d9627d8b72dfa67dd8f8707e724595818bf7e11cb92ec8880633e969fca59a44", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3905f990a55c1ddbd0de56406536faf91e13f8f41743587a9bd15971d1e08d9496ef1467948c863257520f499b37695888a6f2bd786ffb73289ca1d049aa4209"}
02:46:58 INFO - Running post-run listener: copy_logs_to_upload_dir
02:46:58 INFO - Copying logs to upload dir...
02:46:58 INFO - mkdir: /builds/slave/test/build/upload/logs
02:46:58 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=680.022758
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 20 secs) (at 2017-10-28 02:46:58.629407) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-28 02:46:58.633259) =========
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=769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e471460c51c39b72f58aab83050511669d6a35be03c1de4e4acea59585113946f04f5ebadaf9a7c716c2cd30f29b69d66cc0b18f9aacf896ffd66d27b2df62", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfcc463d5ca92d6521ba9ff2d45495dd3d511375576063d9dc50efd4bb20e53de92712697dfb8eb722fe09a03ad2b735afaf433fd04c60abc88534be034aecf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62633f4315516a454ebb1665956d9902e386b34961322b285008f782ab44fc7d9627d8b72dfa67dd8f8707e724595818bf7e11cb92ec8880633e969fca59a44", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3905f990a55c1ddbd0de56406536faf91e13f8f41743587a9bd15971d1e08d9496ef1467948c863257520f499b37695888a6f2bd786ffb73289ca1d049aa4209"}
build_url:https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011314
build_url: 'https://queue.taskcluster.net/v1/task/aS3dd_IMTaiTzlVDHILLBA/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02e471460c51c39b72f58aab83050511669d6a35be03c1de4e4acea59585113946f04f5ebadaf9a7c716c2cd30f29b69d66cc0b18f9aacf896ffd66d27b2df62", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bdfcc463d5ca92d6521ba9ff2d45495dd3d511375576063d9dc50efd4bb20e53de92712697dfb8eb722fe09a03ad2b735afaf433fd04c60abc88534be034aecf", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b62633f4315516a454ebb1665956d9902e386b34961322b285008f782ab44fc7d9627d8b72dfa67dd8f8707e724595818bf7e11cb92ec8880633e969fca59a44", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3905f990a55c1ddbd0de56406536faf91e13f8f41743587a9bd15971d1e08d9496ef1467948c863257520f499b37695888a6f2bd786ffb73289ca1d049aa4209"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-28 02:46:58.666147) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 02:46:58.666462) =========
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=769a0cfe1e83e11e6f11f5a3000001a8-1509180211.262917-448858667
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.005068
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-28 02:46:58.752068) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-28 02:46:58.752436) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-28 02:46:58.752756) =========
========= Total master_lag: 0.22 =========