builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-089
starttime: 1508481766.36
results: success (0)
revision: e2d00910855b5059f92e765097c90dd0f55db7d0
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.360071) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.360486) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.360755) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.480389) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.480855) =========
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=8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-19 23:42:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 221M=0s
2017-10-19 23:42:46 (221 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.134357
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.643631) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.643979) =========
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=8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.015518
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.697405) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:46.697942) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e2d00910855b5059f92e765097c90dd0f55db7d0 --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 e2d00910855b5059f92e765097c90dd0f55db7d0 --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=8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-19 23:42:46,745 truncating revision to first 12 chars
2017-10-19 23:42:46,745 Setting DEBUG logging.
2017-10-19 23:42:46,745 attempt 1/10
2017-10-19 23:42:46,745 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e2d00910855b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-19 23:42:47,009 unpacking tar archive at: mozilla-central-e2d00910855b/testing/mozharness/
program finished with exit code 0
elapsedTime=0.918826
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:47.637113) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:47.637481) =========
script_repo_revision: e2d00910855b5059f92e765097c90dd0f55db7d0
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:47.637883) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:47.638145) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-19 23:42:47.657712) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 56 secs) (at 2017-10-19 23:42:47.658005) =========
/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=8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
23:42:47 INFO - MultiFileLogger online at 20171019 23:42:47 in /builds/slave/test
23:42:47 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
23:42:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
23:42:47 INFO - {'append_to_log': False,
23:42:47 INFO - 'base_work_dir': '/builds/slave/test',
23:42:47 INFO - 'blob_upload_branch': 'Firefox',
23:42:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
23:42:47 INFO - 'branch': 'Firefox',
23:42:47 INFO - 'buildbot_json_path': 'buildprops.json',
23:42:47 INFO - 'code_coverage': False,
23:42:47 INFO - 'config_files': ('talos/linux_config.py',),
23:42:47 INFO - 'default_actions': ('clobber',
23:42:47 INFO - 'read-buildbot-config',
23:42:47 INFO - 'download-and-extract',
23:42:47 INFO - 'populate-webroot',
23:42:47 INFO - 'create-virtualenv',
23:42:47 INFO - 'install',
23:42:47 INFO - 'setup-mitmproxy',
23:42:47 INFO - 'run-tests'),
23:42:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
23:42:47 INFO - 'disable_ccov_upload': False,
23:42:47 INFO - 'disable_stylo': False,
23:42:47 INFO - 'download_minidump_stackwalk': True,
23:42:47 INFO - 'download_symbols': 'ondemand',
23:42:47 INFO - 'enable_stylo': False,
23:42:47 INFO - 'enable_webrender': False,
23:42:47 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
23:42:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
23:42:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
23:42:47 INFO - 'gecko_profile': False,
23:42:47 INFO - 'gecko_profile_interval': 0,
23:42:47 INFO - 'installer_path': 'installer.exe',
23:42:47 INFO - 'log_level': 'info',
23:42:47 INFO - 'log_name': 'talos',
23:42:47 INFO - 'log_to_console': True,
23:42:47 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
23:42:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
23:42:47 INFO - 'opt_config_files': (),
23:42:47 INFO - 'pip_index': False,
23:42:47 INFO - 'suite': 'g4-e10s',
23:42:47 INFO - 'system_bits': '32',
23:42:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
23:42:47 INFO - 'title': 'talos-linux64-ix-089',
23:42:47 INFO - 'tooltool_cache': '/builds/tooltool_cache',
23:42:47 INFO - 'use_talos_json': True,
23:42:47 INFO - 'verify': 'False',
23:42:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
23:42:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
23:42:47 INFO - 'work_dir': 'build'}
23:42:47 INFO - [mozharness: 2017-10-20 06:42:47.845775Z] Running clobber step.
23:42:47 INFO - Running pre-action listener: _resource_record_pre_action
23:42:47 INFO - Running main action method: clobber
23:42:47 INFO - rmtree: /builds/slave/test/build
23:42:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
23:42:49 INFO - Running post-action listener: _resource_record_post_action
23:42:49 INFO - [mozharness: 2017-10-20 06:42:49.414989Z] Finished clobber step (success)
23:42:49 INFO - [mozharness: 2017-10-20 06:42:49.415084Z] Running read-buildbot-config step.
23:42:49 INFO - Running pre-action listener: _resource_record_pre_action
23:42:49 INFO - Running main action method: read_buildbot_config
23:42:49 INFO - Using buildbot properties:
23:42:49 INFO - {
23:42:49 INFO - "project": "",
23:42:49 INFO - "product": "firefox",
23:42:49 INFO - "who": "ffxbld@noreply.mozilla.org",
23:42:49 INFO - "installer_path": "public/build/target.tar.bz2",
23:42:49 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
23:42:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
23:42:49 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
23:42:49 INFO - "stage_platform": "linux64-qr",
23:42:49 INFO - "basedir": "/builds/slave/test",
23:42:49 INFO - "buildnumber": 42,
23:42:49 INFO - "platform": "ubuntu64_hw_qr",
23:42:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
23:42:49 INFO - "slavebuilddir": "test",
23:42:49 INFO - "taskId": "Blh3O6V2Qf2Pbh4iy09Pmg",
23:42:49 INFO - "branch": "mozilla-central",
23:42:49 INFO - "script_repo_revision": "production",
23:42:49 INFO - "revision": "e2d00910855b5059f92e765097c90dd0f55db7d0",
23:42:49 INFO - "slavename": "talos-linux64-ix-089",
23:42:49 INFO - "repo_path": "mozilla-central"
23:42:49 INFO - }
23:42:49 INFO - Finding installer, test and symbols from parent task.
23:42:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Blh3O6V2Qf2Pbh4iy09Pmg'}, attempt #1
23:42:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Blh3O6V2Qf2Pbh4iy09Pmg'}, attempt #1
23:42:50 INFO - Task dependencies: LUX-7JAQS26AWNdzpZhYIw
23:42:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw'}, attempt #1
23:42:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2
23:42:50 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.test_packages.json
23:42:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.crashreporter-symbols.zip
23:42:50 INFO - Running post-action listener: _resource_record_post_action
23:42:50 INFO - [mozharness: 2017-10-20 06:42:50.752373Z] Finished read-buildbot-config step (success)
23:42:50 INFO - [mozharness: 2017-10-20 06:42:50.752587Z] Running download-and-extract step.
23:42:50 INFO - Running pre-action listener: _resource_record_pre_action
23:42:50 INFO - Running main action method: download_and_extract
23:42:50 INFO - mkdir: /builds/slave/test/build/tests
23:42:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:42:50 INFO - trying https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.test_packages.json
23:42:50 INFO - Downloading https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
23:42:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
23:42:51 INFO - Downloaded 989 bytes.
23:42:51 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
23:42:51 INFO - Using the following test package requirements:
23:42:51 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
23:42:51 INFO - u'common': [u'target.common.tests.zip'],
23:42:51 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
23:42:51 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
23:42:51 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
23:42:51 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
23:42:51 INFO - u'mozbase': [u'target.common.tests.zip'],
23:42:51 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
23:42:51 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
23:42:51 INFO - u'web-platform': [u'target.common.tests.zip',
23:42:51 INFO - u'target.web-platform.tests.tar.gz'],
23:42:51 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
23:42:51 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
23:42:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.common.tests.zip
23:42:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.common.tests.zip'}, attempt #1
23:42:51 INFO - Fetch https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.common.tests.zip into memory
23:42:54 INFO - Content-Length response header: 55949740
23:42:54 INFO - Bytes received: 55949740
23:43:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.talos.tests.zip
23:43:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
23:43:04 INFO - Fetch https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.talos.tests.zip into memory
23:43:06 INFO - Content-Length response header: 18737502
23:43:06 INFO - Bytes received: 18737502
23:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:07 INFO - trying https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2
23:43:07 INFO - Downloading https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
23:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
23:43:11 INFO - Downloaded 62537401 bytes.
23:43:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2
23:43:11 INFO - mkdir: /builds/slave/test/properties
23:43:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
23:43:11 INFO - Writing to file /builds/slave/test/properties/build_url
23:43:11 INFO - Contents:
23:43:11 INFO - build_url:https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2
23:43:11 INFO - Running post-action listener: _resource_record_post_action
23:43:11 INFO - Running post-action listener: find_tests_for_verification
23:43:11 INFO - Running post-action listener: set_extra_try_arguments
23:43:11 INFO - [mozharness: 2017-10-20 06:43:11.073987Z] Finished download-and-extract step (success)
23:43:11 INFO - [mozharness: 2017-10-20 06:43:11.074089Z] Running populate-webroot step.
23:43:11 INFO - Running pre-action listener: _resource_record_pre_action
23:43:11 INFO - Running main action method: populate_webroot
23:43:11 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
23:43:11 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['tresize',
23:43:11 INFO - 'tcanvasmark']},
23:43:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
23:43:11 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['dromaeo_css',
23:43:11 INFO - 'kraken']},
23:43:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
23:43:11 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
23:43:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'tests': ['damp', 'tps']},
23:43:11 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['damp', 'tps']},
23:43:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
23:43:11 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['dromaeo_dom']},
23:43:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
23:43:11 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['basic_compositor_video',
23:43:11 INFO - 'glvideo']},
23:43:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
23:43:11 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['ts_paint_webext',
23:43:11 INFO - 'tp5o_webext']},
23:43:11 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
23:43:11 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
23:43:11 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
23:43:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
23:43:11 INFO - 'talos_options': ['--mitmproxy',
23:43:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
23:43:11 INFO - '--firstNonBlankPaint'],
23:43:11 INFO - 'tests': ['tp6_google_heavy',
23:43:11 INFO - 'tp6_youtube_heavy',
23:43:11 INFO - 'tp6_amazon_heavy',
23:43:11 INFO - 'tp6_facebook_heavy']},
23:43:11 INFO - 'other-e10s': {'tests': ['a11yr',
23:43:11 INFO - 'ts_paint',
23:43:11 INFO - 'tpaint',
23:43:11 INFO - 'sessionrestore',
23:43:11 INFO - 'sessionrestore_many_windows',
23:43:11 INFO - 'sessionrestore_no_auto_restore',
23:43:11 INFO - 'tabpaint',
23:43:11 INFO - 'cpstartup']},
23:43:11 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['a11yr',
23:43:11 INFO - 'ts_paint',
23:43:11 INFO - 'tpaint',
23:43:11 INFO - 'sessionrestore',
23:43:11 INFO - 'sessionrestore_many_windows',
23:43:11 INFO - 'sessionrestore_no_auto_restore',
23:43:11 INFO - 'tabpaint',
23:43:11 INFO - 'cpstartup']},
23:43:11 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
23:43:11 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
23:43:11 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['perf_reftest_singletons']},
23:43:11 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['perf_reftest']},
23:43:11 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
23:43:11 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['speedometer']},
23:43:11 INFO - 'svgr-e10s': {'tests': ['tsvgx',
23:43:11 INFO - 'tsvgr_opacity',
23:43:11 INFO - 'tart',
23:43:11 INFO - 'tscrollx',
23:43:11 INFO - 'tsvg_static']},
23:43:11 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['tsvgx',
23:43:11 INFO - 'tsvgr_opacity',
23:43:11 INFO - 'tart',
23:43:11 INFO - 'tscrollx',
23:43:11 INFO - 'tsvg_static']},
23:43:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
23:43:11 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'talos_options': ['--disable-stylo'],
23:43:11 INFO - 'tests': ['tp5o']},
23:43:11 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
23:43:11 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
23:43:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
23:43:11 INFO - 'talos_options': ['--mitmproxy',
23:43:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
23:43:11 INFO - '--firstNonBlankPaint'],
23:43:11 INFO - 'tests': ['tp6_google',
23:43:11 INFO - 'tp6_youtube',
23:43:11 INFO - 'tp6_amazon',
23:43:11 INFO - 'tp6_facebook']},
23:43:11 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
23:43:11 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
23:43:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
23:43:11 INFO - 'talos_options': ['--disable-stylo',
23:43:11 INFO - '--mitmproxy',
23:43:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
23:43:11 INFO - '--firstNonBlankPaint'],
23:43:11 INFO - 'tests': ['tp6_google',
23:43:11 INFO - 'tp6_youtube',
23:43:11 INFO - 'tp6_amazon',
23:43:11 INFO - 'tp6_facebook']},
23:43:11 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
23:43:11 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
23:43:11 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
23:43:11 INFO - 'talos_options': ['--stylo-threads=1',
23:43:11 INFO - '--mitmproxy',
23:43:11 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
23:43:11 INFO - '--firstNonBlankPaint'],
23:43:11 INFO - 'tests': ['tp6_google',
23:43:11 INFO - 'tp6_youtube',
23:43:11 INFO - 'tp6_amazon',
23:43:11 INFO - 'tp6_facebook']},
23:43:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'talos_options': ['--xperf_path',
23:43:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
23:43:11 INFO - 'tests': ['tp5n']},
23:43:11 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
23:43:11 INFO - 'talos_options': ['--disable-stylo',
23:43:11 INFO - '--xperf_path',
23:43:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
23:43:11 INFO - 'tests': ['tp5n']}}}
23:43:11 INFO - Running post-action listener: _resource_record_post_action
23:43:11 INFO - [mozharness: 2017-10-20 06:43:11.092123Z] Finished populate-webroot step (success)
23:43:11 INFO - [mozharness: 2017-10-20 06:43:11.092210Z] Running create-virtualenv step.
23:43:11 INFO - Running pre-action listener: _resource_record_pre_action
23:43:11 INFO - Running main action method: create_virtualenv
23:43:11 INFO - Creating virtualenv /builds/slave/test/build/venv
23:43:11 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
23:43:11 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
23:43:11 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
23:43:11 INFO - Using real prefix '/usr'
23:43:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python
23:43:11 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
23:43:13 INFO - Installing setuptools, pip, wheel...done.
23:43:13 INFO - Return code: 0
23:43:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
23:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:13 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:13 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
23:43:13 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
23:43:13 INFO - Using env: {'DISPLAY': ':0',
23:43:13 INFO - 'HOME': '/home/cltbld',
23:43:13 INFO - 'LANG': 'en_US.UTF-8',
23:43:13 INFO - 'LANGUAGE': 'en_US:en',
23:43:13 INFO - 'LOGNAME': 'cltbld',
23:43:13 INFO - 'MAIL': '/var/mail/cltbld',
23:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:13 INFO - 'MOZ_NO_REMOTE': '1',
23:43:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:13 INFO - 'NO_EM_RESTART': '1',
23:43:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:13 INFO - 'PWD': '/builds/slave/test',
23:43:13 INFO - 'SHELL': '/bin/bash',
23:43:13 INFO - 'SHLVL': '1',
23:43:13 INFO - 'TERM': 'linux',
23:43:13 INFO - 'TMOUT': '86400',
23:43:13 INFO - 'USER': 'cltbld',
23:43:13 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:13 INFO - '_': '/tools/buildbot/bin/python'}
23:43:13 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
23:43:13 INFO - Return code: 0
23:43:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
23:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:13 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:13 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
23:43:13 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
23:43:13 INFO - Using env: {'DISPLAY': ':0',
23:43:13 INFO - 'HOME': '/home/cltbld',
23:43:13 INFO - 'LANG': 'en_US.UTF-8',
23:43:13 INFO - 'LANGUAGE': 'en_US:en',
23:43:13 INFO - 'LOGNAME': 'cltbld',
23:43:13 INFO - 'MAIL': '/var/mail/cltbld',
23:43:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:13 INFO - 'MOZ_NO_REMOTE': '1',
23:43:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:13 INFO - 'NO_EM_RESTART': '1',
23:43:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:13 INFO - 'PWD': '/builds/slave/test',
23:43:13 INFO - 'SHELL': '/bin/bash',
23:43:13 INFO - 'SHLVL': '1',
23:43:13 INFO - 'TERM': 'linux',
23:43:13 INFO - 'TMOUT': '86400',
23:43:13 INFO - 'USER': 'cltbld',
23:43:13 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:13 INFO - '_': '/tools/buildbot/bin/python'}
23:43:14 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:14 INFO - Collecting psutil>=3.1.1
23:43:15 INFO - Installing collected packages: psutil
23:43:15 INFO - Successfully installed psutil-3.1.1
23:43:15 INFO - Return code: 0
23:43:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
23:43:15 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')]}
23:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:15 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')]}
23:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:15 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:15 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
23:43:15 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
23:43:15 INFO - Using env: {'DISPLAY': ':0',
23:43:15 INFO - 'HOME': '/home/cltbld',
23:43:15 INFO - 'LANG': 'en_US.UTF-8',
23:43:15 INFO - 'LANGUAGE': 'en_US:en',
23:43:15 INFO - 'LOGNAME': 'cltbld',
23:43:15 INFO - 'MAIL': '/var/mail/cltbld',
23:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:15 INFO - 'MOZ_NO_REMOTE': '1',
23:43:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:15 INFO - 'NO_EM_RESTART': '1',
23:43:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:15 INFO - 'PWD': '/builds/slave/test',
23:43:15 INFO - 'SHELL': '/bin/bash',
23:43:15 INFO - 'SHLVL': '1',
23:43:15 INFO - 'TERM': 'linux',
23:43:15 INFO - 'TMOUT': '86400',
23:43:15 INFO - 'USER': 'cltbld',
23:43:15 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:15 INFO - '_': '/tools/buildbot/bin/python'}
23:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:15 INFO - Collecting mozsystemmonitor==0.3
23:43:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
23:43:16 INFO - Installing collected packages: mozsystemmonitor
23:43:16 INFO - Successfully installed mozsystemmonitor-0.3
23:43:17 INFO - Return code: 0
23:43:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
23:43:17 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')]}
23:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:17 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')]}
23:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:17 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:17 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
23:43:17 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
23:43:17 INFO - Using env: {'DISPLAY': ':0',
23:43:17 INFO - 'HOME': '/home/cltbld',
23:43:17 INFO - 'LANG': 'en_US.UTF-8',
23:43:17 INFO - 'LANGUAGE': 'en_US:en',
23:43:17 INFO - 'LOGNAME': 'cltbld',
23:43:17 INFO - 'MAIL': '/var/mail/cltbld',
23:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:17 INFO - 'MOZ_NO_REMOTE': '1',
23:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:17 INFO - 'NO_EM_RESTART': '1',
23:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:17 INFO - 'PWD': '/builds/slave/test',
23:43:17 INFO - 'SHELL': '/bin/bash',
23:43:17 INFO - 'SHLVL': '1',
23:43:17 INFO - 'TERM': 'linux',
23:43:17 INFO - 'TMOUT': '86400',
23:43:17 INFO - 'USER': 'cltbld',
23:43:17 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:17 INFO - '_': '/tools/buildbot/bin/python'}
23:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:17 INFO - Collecting jsonschema==2.5.1
23:43:18 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
23:43:18 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
23:43:19 INFO - Installing collected packages: functools32, jsonschema
23:43:19 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
23:43:19 INFO - Return code: 0
23:43:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
23:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:19 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:19 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
23:43:19 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
23:43:19 INFO - Using env: {'DISPLAY': ':0',
23:43:19 INFO - 'HOME': '/home/cltbld',
23:43:19 INFO - 'LANG': 'en_US.UTF-8',
23:43:19 INFO - 'LANGUAGE': 'en_US:en',
23:43:19 INFO - 'LOGNAME': 'cltbld',
23:43:19 INFO - 'MAIL': '/var/mail/cltbld',
23:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:19 INFO - 'MOZ_NO_REMOTE': '1',
23:43:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:19 INFO - 'NO_EM_RESTART': '1',
23:43:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:19 INFO - 'PWD': '/builds/slave/test',
23:43:19 INFO - 'SHELL': '/bin/bash',
23:43:19 INFO - 'SHLVL': '1',
23:43:19 INFO - 'TERM': 'linux',
23:43:19 INFO - 'TMOUT': '86400',
23:43:19 INFO - 'USER': 'cltbld',
23:43:19 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:19 INFO - '_': '/tools/buildbot/bin/python'}
23:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
23:43:20 INFO - Return code: 0
23:43:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
23:43:20 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')]}
23:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:20 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')]}
23:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:20 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:20 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
23:43:20 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
23:43:20 INFO - Using env: {'DISPLAY': ':0',
23:43:20 INFO - 'HOME': '/home/cltbld',
23:43:20 INFO - 'LANG': 'en_US.UTF-8',
23:43:20 INFO - 'LANGUAGE': 'en_US:en',
23:43:20 INFO - 'LOGNAME': 'cltbld',
23:43:20 INFO - 'MAIL': '/var/mail/cltbld',
23:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:20 INFO - 'MOZ_NO_REMOTE': '1',
23:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:20 INFO - 'NO_EM_RESTART': '1',
23:43:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:20 INFO - 'PWD': '/builds/slave/test',
23:43:20 INFO - 'SHELL': '/bin/bash',
23:43:20 INFO - 'SHLVL': '1',
23:43:20 INFO - 'TERM': 'linux',
23:43:20 INFO - 'TMOUT': '86400',
23:43:20 INFO - 'USER': 'cltbld',
23:43:20 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:20 INFO - '_': '/tools/buildbot/bin/python'}
23:43:20 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:20 INFO - Collecting blobuploader==1.2.4
23:43:21 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
23:43:22 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
23:43:23 INFO - Installing collected packages: requests, docopt, blobuploader
23:43:23 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
23:43:23 INFO - Return code: 0
23:43:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv
23:43: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')]}
23:43:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43: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')]}
23:43:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:23 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:23 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
23:43:23 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
23:43:23 INFO - Using env: {'DISPLAY': ':0',
23:43:23 INFO - 'HOME': '/home/cltbld',
23:43:23 INFO - 'LANG': 'en_US.UTF-8',
23:43:23 INFO - 'LANGUAGE': 'en_US:en',
23:43:23 INFO - 'LOGNAME': 'cltbld',
23:43:23 INFO - 'MAIL': '/var/mail/cltbld',
23:43:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:23 INFO - 'MOZ_NO_REMOTE': '1',
23:43:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:23 INFO - 'NO_EM_RESTART': '1',
23:43:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:23 INFO - 'PWD': '/builds/slave/test',
23:43:23 INFO - 'SHELL': '/bin/bash',
23:43:23 INFO - 'SHLVL': '1',
23:43:23 INFO - 'TERM': 'linux',
23:43:23 INFO - 'TMOUT': '86400',
23:43:23 INFO - 'USER': 'cltbld',
23:43:23 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:23 INFO - '_': '/tools/buildbot/bin/python'}
23:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:24 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
23:43:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
23:43:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
23:43:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
23:43:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
23:43:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
23:43:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
23:43:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
23:43:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
23:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
23:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
23:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
23:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
23:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
23:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
23:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
23:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
23:43:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
23:43:27 INFO - Running setup.py install for manifestparser: started
23:43:27 INFO - Running setup.py install for manifestparser: finished with status 'done'
23:43:27 INFO - Running setup.py install for mozcrash: started
23:43:27 INFO - Running setup.py install for mozcrash: finished with status 'done'
23:43:27 INFO - Running setup.py install for mozdebug: started
23:43:28 INFO - Running setup.py install for mozdebug: finished with status 'done'
23:43:28 INFO - Running setup.py install for mozdevice: started
23:43:28 INFO - Running setup.py install for mozdevice: finished with status 'done'
23:43:28 INFO - Running setup.py install for mozfile: started
23:43:28 INFO - Running setup.py install for mozfile: finished with status 'done'
23:43:28 INFO - Running setup.py install for mozhttpd: started
23:43:28 INFO - Running setup.py install for mozhttpd: finished with status 'done'
23:43:28 INFO - Running setup.py install for mozinfo: started
23:43:29 INFO - Running setup.py install for mozinfo: finished with status 'done'
23:43:29 INFO - Running setup.py install for mozInstall: started
23:43:29 INFO - Running setup.py install for mozInstall: finished with status 'done'
23:43:29 INFO - Running setup.py install for mozleak: started
23:43:29 INFO - Running setup.py install for mozleak: finished with status 'done'
23:43:29 INFO - Running setup.py install for mozlog: started
23:43:29 INFO - Running setup.py install for mozlog: finished with status 'done'
23:43:29 INFO - Running setup.py install for moznetwork: started
23:43:29 INFO - Running setup.py install for moznetwork: finished with status 'done'
23:43:29 INFO - Running setup.py install for mozprocess: started
23:43:30 INFO - Running setup.py install for mozprocess: finished with status 'done'
23:43:30 INFO - Running setup.py install for mozprofile: started
23:43:30 INFO - Running setup.py install for mozprofile: finished with status 'done'
23:43:30 INFO - Running setup.py install for mozrunner: started
23:43:30 INFO - Running setup.py install for mozrunner: finished with status 'done'
23:43:30 INFO - Running setup.py install for mozscreenshot: started
23:43:30 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
23:43:30 INFO - Running setup.py install for moztest: started
23:43:31 INFO - Running setup.py install for moztest: finished with status 'done'
23:43:31 INFO - Running setup.py install for mozversion: started
23:43:31 INFO - Running setup.py install for mozversion: finished with status 'done'
23:43:31 INFO - Successfully installed manifestparser-1.1 mozInstall-1.13 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.28 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
23:43:31 INFO - Return code: 0
23:43:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv
23:43:31 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')]}
23:43:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:31 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')]}
23:43:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:31 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:31 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
23:43:31 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
23:43:31 INFO - Using env: {'DISPLAY': ':0',
23:43:31 INFO - 'HOME': '/home/cltbld',
23:43:31 INFO - 'LANG': 'en_US.UTF-8',
23:43:31 INFO - 'LANGUAGE': 'en_US:en',
23:43:31 INFO - 'LOGNAME': 'cltbld',
23:43:31 INFO - 'MAIL': '/var/mail/cltbld',
23:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:31 INFO - 'MOZ_NO_REMOTE': '1',
23:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:31 INFO - 'NO_EM_RESTART': '1',
23:43:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:31 INFO - 'PWD': '/builds/slave/test',
23:43:31 INFO - 'SHELL': '/bin/bash',
23:43:31 INFO - 'SHLVL': '1',
23:43:31 INFO - 'TERM': 'linux',
23:43:31 INFO - 'TMOUT': '86400',
23:43:31 INFO - 'USER': 'cltbld',
23:43:31 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:31 INFO - '_': '/tools/buildbot/bin/python'}
23:43:31 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:31 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
23:43:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
23:43:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
23:43:32 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))
23:43:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
23:43:32 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))
23:43:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
23:43:32 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))
23:43:32 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
23:43:33 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))
23:43:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
23:43:33 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))
23:43:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
23:43:33 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))
23:43:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
23:43:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
23:43:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
23:43:33 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))
23:43:33 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
23:43:34 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))
23:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
23:43:34 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))
23:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
23:43:34 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))
23:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
23:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
23:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
23:43:34 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))
23:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
23:43:35 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))
23:43:35 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
23:43:35 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))
23:43:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
23:43:35 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))
23:43:35 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))
23:43:35 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))
23:43:35 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))
23:43:35 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))
23:43:35 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))
23:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
23:43:35 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
23:43:36 INFO - Installing collected packages: blessings
23:43:36 INFO - Successfully installed blessings-1.6
23:43:36 INFO - Return code: 0
23:43:36 INFO - Done creating virtualenv /builds/slave/test/build/venv.
23:43:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
23:43:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
23:43:37 INFO - Reading from file tmpfile_stdout
23:43:37 INFO - Reading from file tmpfile_stderr
23:43:37 INFO - Current package versions:
23:43:37 INFO - blessings == 1.6
23:43:37 INFO - blobuploader == 1.2.4
23:43:37 INFO - docopt == 0.6.1
23:43:37 INFO - functools32 == 3.2.3.post2
23:43:37 INFO - jsonschema == 2.5.1
23:43:37 INFO - manifestparser == 1.1
23:43:37 INFO - mozInstall == 1.13
23:43:37 INFO - mozcrash == 1.0
23:43:37 INFO - mozdebug == 0.1
23:43:37 INFO - mozdevice == 0.51
23:43:37 INFO - mozfile == 1.2
23:43:37 INFO - mozhttpd == 0.7
23:43:37 INFO - mozinfo == 0.10
23:43:37 INFO - mozleak == 0.1
23:43:37 INFO - mozlog == 3.5
23:43:37 INFO - moznetwork == 0.27
23:43:37 INFO - mozprocess == 0.25
23:43:37 INFO - mozprofile == 0.28
23:43:37 INFO - mozrunner == 6.13
23:43:37 INFO - mozscreenshot == 0.1
23:43:37 INFO - mozsystemmonitor == 0.3
23:43:37 INFO - moztest == 0.8
23:43:37 INFO - mozversion == 1.4
23:43:37 INFO - psutil == 3.1.1
23:43:37 INFO - requests == 1.2.3
23:43:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv
23:43:37 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')]}
23:43:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:37 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')]}
23:43:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:37 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:37 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
23:43:37 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
23:43:37 INFO - Using env: {'DISPLAY': ':0',
23:43:37 INFO - 'HOME': '/home/cltbld',
23:43:37 INFO - 'LANG': 'en_US.UTF-8',
23:43:37 INFO - 'LANGUAGE': 'en_US:en',
23:43:37 INFO - 'LOGNAME': 'cltbld',
23:43:37 INFO - 'MAIL': '/var/mail/cltbld',
23:43:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:37 INFO - 'MOZ_NO_REMOTE': '1',
23:43:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:37 INFO - 'NO_EM_RESTART': '1',
23:43:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:37 INFO - 'PWD': '/builds/slave/test',
23:43:37 INFO - 'SHELL': '/bin/bash',
23:43:37 INFO - 'SHLVL': '1',
23:43:37 INFO - 'TERM': 'linux',
23:43:37 INFO - 'TMOUT': '86400',
23:43:37 INFO - 'USER': 'cltbld',
23:43:37 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:37 INFO - '_': '/tools/buildbot/bin/python'}
23:43:37 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:37 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))
23:43:37 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))
23:43:37 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))
23:43:37 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))
23:43:37 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))
23:43:37 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))
23:43:37 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))
23:43:37 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))
23:43:37 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))
23:43:37 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
23:43:38 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
23:43:39 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))
23:43:39 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))
23:43:39 INFO - Installing collected packages: simplejson, requests
23:43:39 INFO - Found existing installation: requests 1.2.3
23:43:39 INFO - Uninstalling requests-1.2.3:
23:43:39 INFO - Successfully uninstalled requests-1.2.3
23:43:40 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
23:43:40 INFO - Return code: 0
23:43:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
23:43:40 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')]}
23:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
23:43:40 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')]}
23:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
23:43:40 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 0x1c7c1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1fe9cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, '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': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592', '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
23:43:40 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
23:43:40 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
23:43:40 INFO - Using env: {'DISPLAY': ':0',
23:43:40 INFO - 'HOME': '/home/cltbld',
23:43:40 INFO - 'LANG': 'en_US.UTF-8',
23:43:40 INFO - 'LANGUAGE': 'en_US:en',
23:43:40 INFO - 'LOGNAME': 'cltbld',
23:43:40 INFO - 'MAIL': '/var/mail/cltbld',
23:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:40 INFO - 'MOZ_NO_REMOTE': '1',
23:43:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:40 INFO - 'NO_EM_RESTART': '1',
23:43:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:40 INFO - 'PWD': '/builds/slave/test',
23:43:40 INFO - 'SHELL': '/bin/bash',
23:43:40 INFO - 'SHLVL': '1',
23:43:40 INFO - 'TERM': 'linux',
23:43:40 INFO - 'TMOUT': '86400',
23:43:40 INFO - 'USER': 'cltbld',
23:43:40 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:40 INFO - '_': '/tools/buildbot/bin/python'}
23:43:40 INFO - Ignoring indexes: https://pypi.python.org/simple
23:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
23:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
23:43:40 INFO - Return code: 0
23:43:40 INFO - Running post-action listener: _resource_record_post_action
23:43:40 INFO - Running post-action listener: _start_resource_monitoring
23:43:40 INFO - Starting resource monitoring.
23:43:40 INFO - [mozharness: 2017-10-20 06:43:40.738601Z] Finished create-virtualenv step (success)
23:43:40 INFO - [mozharness: 2017-10-20 06:43:40.739099Z] Running install step.
23:43:40 INFO - Running pre-action listener: _resource_record_pre_action
23:43:40 INFO - Running main action method: install
23:43:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
23:43:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
23:43:41 INFO - Reading from file tmpfile_stdout
23:43:41 INFO - Reading from file tmpfile_stderr
23:43:41 INFO - Detecting whether we're running mozinstall >=1.0...
23:43:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
23:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
23:43:41 INFO - Reading from file tmpfile_stdout
23:43:41 INFO - Output received:
23:43:41 INFO - Usage: mozinstall [options] installer
23:43:41 INFO - Options:
23:43:41 INFO - -h, --help show this help message and exit
23:43:41 INFO - -d DEST, --destination=DEST
23:43:41 INFO - Directory to install application into. [default:
23:43:41 INFO - "/builds/slave/test"]
23:43:41 INFO - --app=APP Application being installed. [default: firefox]
23:43:41 INFO - mkdir: /builds/slave/test/build/application
23:43:41 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']
23:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
23:43:59 INFO - Reading from file tmpfile_stdout
23:43:59 INFO - Output received:
23:43:59 INFO - /builds/slave/test/build/application/firefox/firefox
23:43:59 INFO - Running post-action listener: _resource_record_post_action
23:43:59 INFO - [mozharness: 2017-10-20 06:43:59.557277Z] Finished install step (success)
23:43:59 INFO - [mozharness: 2017-10-20 06:43:59.557523Z] Running setup-mitmproxy step.
23:43:59 INFO - Running pre-action listener: _resource_record_pre_action
23:43:59 INFO - Running main action method: setup_mitmproxy
23:43:59 INFO - Skipping: mitmproxy is not required
23:43:59 INFO - Running post-action listener: _resource_record_post_action
23:43:59 INFO - [mozharness: 2017-10-20 06:43:59.558636Z] Finished setup-mitmproxy step (success)
23:43:59 INFO - [mozharness: 2017-10-20 06:43:59.558854Z] Running run-tests step.
23:43:59 INFO - Running pre-action listener: _resource_record_pre_action
23:43:59 INFO - Running pre-action listener: _set_gcov_prefix
23:43:59 INFO - Running main action method: run_tests
23:43:59 WARNING - Try message not found.
23:43:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
23:43:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
23:43:59 INFO - Python 2.7.3
23:43:59 INFO - Return code: 0
23:43:59 INFO - grabbing minidump binary from tooltool
23:43:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
23:43:59 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 0x1fe6e60>, '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 0x1fe7d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1fe81f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
23:43:59 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
23:43:59 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
23:43:59 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
23:43:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
23:43:59 INFO - Return code: 0
23:43:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
23:43:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
23:43:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
23:43:59 INFO - ENV: RUST_BACKTRACE is now full
23:43:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
23:43:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
23:43:59 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/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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
23:43:59 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/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-089 --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
23:43:59 INFO - Using env: {'DISPLAY': ':0',
23:43:59 INFO - 'HOME': '/home/cltbld',
23:43:59 INFO - 'LANG': 'en_US.UTF-8',
23:43:59 INFO - 'LANGUAGE': 'en_US:en',
23:43:59 INFO - 'LOGNAME': 'cltbld',
23:43:59 INFO - 'MAIL': '/var/mail/cltbld',
23:43:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
23:43:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
23:43:59 INFO - 'MOZ_ACCELERATED': '1',
23:43:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:43:59 INFO - 'MOZ_NO_REMOTE': '1',
23:43:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
23:43:59 INFO - 'MOZ_WEBRENDER': '1',
23:43:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:43:59 INFO - 'NO_EM_RESTART': '1',
23:43:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:43:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:43:59 INFO - 'PWD': '/builds/slave/test',
23:43:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
23:43:59 INFO - 'RUST_BACKTRACE': 'full',
23:43:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
23:43:59 INFO - 'SHELL': '/bin/bash',
23:43:59 INFO - 'SHLVL': '1',
23:43:59 INFO - 'TERM': 'linux',
23:43:59 INFO - 'TMOUT': '86400',
23:43:59 INFO - 'USER': 'cltbld',
23:43:59 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:43:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:43:59 INFO - '_': '/tools/buildbot/bin/python'}
23:43:59 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/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-089', '--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
23:43:59 INFO - mozversion application_buildid: 20171020054543
23:43:59 INFO - mozversion application_changeset: e2d00910855b5059f92e765097c90dd0f55db7d0
23:43:59 INFO - mozversion application_display_name: Nightly
23:43:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
23:43:59 INFO - mozversion application_name: Firefox
23:43:59 INFO - mozversion application_remotingname: firefox
23:43:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
23:43:59 INFO - mozversion application_vendor: Mozilla
23:43:59 INFO - mozversion application_version: 58.0a1
23:43:59 INFO - mozversion platform_buildid: 20171020054543
23:43:59 INFO - mozversion platform_changeset: e2d00910855b5059f92e765097c90dd0f55db7d0
23:43:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
23:43:59 INFO - mozversion platform_version: 58.0a1
23:43:59 INFO - using testdate: 1508481839
23:43:59 INFO - actual date: 1508481839
23:43:59 INFO - starting webserver on 'localhost:36410'
23:43:59 INFO - SUITE-START | Running 2 tests
23:43:59 INFO - TEST-START | basic_compositor_video
23:43:59 INFO - Initialising browser for basic_compositor_video test...
23:43:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36410/getInfo.html -profile /tmp/tmp0f7xy8/profile
23:43:59 INFO - TEST-INFO | started process 13160 (/builds/slave/test/build/application/firefox/firefox http://localhost:36410/getInfo.html)
23:44:05 INFO - TEST-INFO | 13160: exit 0
23:44:05 INFO - Browser initialized.
23:44:05 INFO - Running cycle 1/1 for basic_compositor_video test...
23:44:05 INFO - Using env: {'DISPLAY': ':0',
23:44:05 INFO - 'HOME': '/home/cltbld',
23:44:05 INFO - 'JSGC_DISABLE_POISONING': '1',
23:44:05 INFO - 'LANG': 'en_US.UTF-8',
23:44:05 INFO - 'LANGUAGE': 'en_US:en',
23:44:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
23:44:05 INFO - 'LOGNAME': 'cltbld',
23:44:05 INFO - 'MAIL': '/var/mail/cltbld',
23:44:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
23:44:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
23:44:05 INFO - 'MOZ_ACCELERATED': '1',
23:44:05 INFO - 'MOZ_CRASHREPORTER': '1',
23:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:44:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
23:44:05 INFO - 'MOZ_NO_REMOTE': '1',
23:44:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
23:44:05 INFO - 'MOZ_WEBRENDER': '1',
23:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:44:05 INFO - 'NO_EM_RESTART': '1',
23:44:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:44:05 INFO - 'PWD': '/builds/slave/test',
23:44:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
23:44:05 INFO - 'RUST_BACKTRACE': 'full',
23:44:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
23:44:05 INFO - 'SHELL': '/bin/bash',
23:44:05 INFO - 'SHLVL': '1',
23:44:05 INFO - 'TERM': 'linux',
23:44:05 INFO - 'TMOUT': '86400',
23:44:05 INFO - 'USER': 'cltbld',
23:44:05 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:44:05 INFO - '_': '/tools/buildbot/bin/python'}
23:44:05 INFO - TEST-INFO | started process 13443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0f7xy8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
23:44:06 INFO - PID 13443 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
23:44:06 INFO - PID 13443 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
23:44:07 INFO - PID 13443 |
23:44:07 INFO - PID 13443 | (/builds/slave/test/build/application/firefox/firefox:13504): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
23:44:07 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.445562347188264 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4219007263922516 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.521172268907563 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4389207317073183 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.115295950155764 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0074887218045108 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.5945941807044415 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.386524122807012 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.688390624999999 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.446366666666671 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.838758064516127 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.653325581395349 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.212283653846148 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759206081081076 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.70007692307692 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.712583892617444 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.654145408163273 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.734999999999994 ms/frame
23:44:53 INFO - PID 13443 |
23:44:53 INFO - PID 13443 | Cycle 1(1): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:45:39 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6743048128342246 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.40687725631769 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.516933724832215 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4516360294117647 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.108787564766839 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0165460030165905 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.4917964071856264 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.35838779956427 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.431846381093059 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.219978902953588 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.958900826446278 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.751805225653203 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.110047393364934 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.7570439189189155 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.6547959183673475 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.691973244147138 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.692320512820525 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.734175084175069 ms/frame
23:45:39 INFO - PID 13443 |
23:45:39 INFO - PID 13443 | Cycle 1(2): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:46:25 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6672977777777778 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.427876213592233 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5257154882154884 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4511397058823543 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.109471502590673 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0086541353383445 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.274586894586895 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.090572597137016 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.45803863298663 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.302182795698924 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.823826366559489 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.652988372093023 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.1428333333333285 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.669916666666662 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.7144601542416344 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.734410774410783 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.692358974358966 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.691371237458198 ms/frame
23:46:25 INFO - PID 13443 |
23:46:25 INFO - PID 13443 | Cycle 1(3): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:47:11 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6691592526690395 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4107771084337344 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.517437080536913 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.442527472527473 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.096522187822496 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.007563909774437 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.298832378223497 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.12430927835051 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.348811594202899 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.107402464065709 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.809110576923074 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.620392609699771 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.075978773584906 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.692140468227415 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.692948717948718 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.6897993311036785 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.712699228791768 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.689866220735775 ms/frame
23:47:11 INFO - PID 13443 |
23:47:11 INFO - PID 13443 | Cycle 1(4): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:47:57 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6507685512367494 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.410644578313253 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5256818181818184 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.442539682539682 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0932783505154635 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.007654135338346 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.367918486171762 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.273301282051279 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.74004739336493 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.506193693693693 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.777993630573251 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.598459770114946 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.144321428571432 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.714848993288595 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.65413265306122 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.646644518272423 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.714730077120819 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.692575250836126 ms/frame
23:47:57 INFO - PID 13443 |
23:47:57 INFO - PID 13443 | Cycle 1(5): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:48:42 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.690865470852018 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.401314525810325 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5189294710327457 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.460922509225093 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.090623069001029 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0130948795180745 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.208302945301541 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.082173469387762 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.545780303030304 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.424845132743361 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.793218849840256 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.621189376443414 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.1783851674641115 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.689096989966558 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.83321148825066 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.802789115646248 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.655561224489804 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.6905016722408 ms/frame
23:48:42 INFO - PID 13443 |
23:48:42 INFO - PID 13443 | Cycle 1(6): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:49:28 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6770695807314895 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.430285540704739 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5090928093645486 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.451164215686275 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.087294238683128 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.017518853695324 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.444925925925927 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.238559322033895 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.336047687861269 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.166624999999999 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.855258899676374 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.642494199535965 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.093250591016539 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.734629629629646 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.715115681233934 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.66774999999999 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.675396419437326 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.713724832214773 ms/frame
23:49:28 INFO - PID 13443 |
23:49:28 INFO - PID 13443 | Cycle 1(7): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:50:14 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6674088888888887 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4282584951456307 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.523280067283431 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4581265356265356 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0968317853457172 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0120105421686754 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.274415954415956 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.115288065843619 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.32335734870317 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.168114583333333 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.97626036484245 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.797050359712228 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.1268527315914545 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.668916666666652 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.77436528497409 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.690652173913035 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.614936548223341 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.736397306397303 ms/frame
23:50:14 INFO - PID 13443 |
23:50:14 INFO - PID 13443 | Cycle 1(8): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:51:00 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.676561106155219 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4164371980676322 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.526022727272727 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4546625766871157 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0994576446280986 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0087593984962417 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.519503012048194 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.367063318777296 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.7169496855345905 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.525576923076923 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 5.008564273789645 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.820289156626511 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.14286904761905 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.734225589225597 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.713791773778922 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.691220735785938 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.713097686375313 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.669366666666659 ms/frame
23:51:00 INFO - PID 13443 |
23:51:00 INFO - PID 13443 | Cycle 1(9): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:51:46 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.674590017825312 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.413504221954162 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5231833473507144 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.444981662591688 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0969091847265227 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.008729323308271 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.638222565687791 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.495629213483147 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.658408385093172 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.4344345898004445 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.832149758454105 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.641426914153136 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.177404306220084 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.716006711409404 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.692487179487179 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.689180602006672 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.675332480818415 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.69053511705686 ms/frame
23:51:46 INFO - PID 13443 |
23:51:46 INFO - PID 13443 | Cycle 1(10): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:52:32 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.669581850533808 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.413516284680338 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5170343959731545 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4572665847665838 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0868621399176956 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.016968325791855 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.464985119047618 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.358507625272332 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.658757763975157 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.416467991169978 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.8559385113268565 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.63021990740741 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.159785202863955 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.714412751677866 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.712763496143958 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.733905723905727 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.67265984654732 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.692073578595319 ms/frame
23:52:32 INFO - PID 13443 |
23:52:32 INFO - PID 13443 | Cycle 1(11): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:53:18 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6866830796777084 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.422524213075061 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.525425084175084 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4520098039215688 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_startup = 3.1089170984455974 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.004204204204203 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_startup = 4.19607692307692 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1_inclip = 4.074765784114058 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.37956204379562 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.159480249480247 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_startup = 4.816709470304978 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.480p.60fps.webm_scale_2_inclip = 4.63040509259259 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.145083333333337 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.671866666666659 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.693089743589744 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7364814814814835 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.71262210796915 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.7126342281879126 ms/frame
23:53:18 INFO - PID 13443 |
23:53:18 INFO - PID 13443 | Cycle 1(12): loaded http://localhost:36410/tests/video/video_playback.html (next: http://localhost:36410/tests/video/video_playback.html)
23:53:19 INFO - PID 13443 | __start_tp_report
23:53:19 INFO - PID 13443 | _x_x_mozilla_page_load
23:53:19 INFO - PID 13443 | _x_x_mozilla_page_load_details
23:53:19 INFO - PID 13443 | |i|pagename|runs|
23:53:19 INFO - PID 13443 | |0;240p.120fps.mp4_scale_1_startup;2.445562347188264;2.6743048128342246;2.6672977777777778;2.6691592526690395;2.6507685512367494;2.690865470852018;2.6770695807314895;2.6674088888888887;2.676561106155219;2.674590017825312;2.669581850533808;2.6866830796777084
23:53:19 INFO - PID 13443 | |1;240p.120fps.mp4_scale_1_inclip;2.4219007263922516;2.40687725631769;2.427876213592233;2.4107771084337344;2.410644578313253;2.401314525810325;2.430285540704739;2.4282584951456307;2.4164371980676322;2.413504221954162;2.413516284680338;2.422524213075061
23:53:19 INFO - PID 13443 | |2;240p.120fps.mp4_scale_1.1_startup;2.521172268907563;2.516933724832215;2.5257154882154884;2.517437080536913;2.5256818181818184;2.5189294710327457;2.5090928093645486;2.523280067283431;2.526022727272727;2.5231833473507144;2.5170343959731545;2.525425084175084
23:53:19 INFO - PID 13443 | |3;240p.120fps.mp4_scale_1.1_inclip;2.4389207317073183;2.4516360294117647;2.4511397058823543;2.442527472527473;2.442539682539682;2.460922509225093;2.451164215686275;2.4581265356265356;2.4546625766871157;2.444981662591688;2.4572665847665838;2.4520098039215688
23:53:19 INFO - PID 13443 | |4;240p.120fps.mp4_scale_2_startup;3.115295950155764;3.108787564766839;3.109471502590673;3.096522187822496;3.0932783505154635;3.090623069001029;3.087294238683128;3.0968317853457172;3.0994576446280986;3.0969091847265227;3.0868621399176956;3.1089170984455974
23:53:19 INFO - PID 13443 | |5;240p.120fps.mp4_scale_2_inclip;3.0074887218045108;3.0165460030165905;3.0086541353383445;3.007563909774437;3.007654135338346;3.0130948795180745;3.017518853695324;3.0120105421686754;3.0087593984962417;3.008729323308271;3.016968325791855;3.004204204204203
23:53:19 INFO - PID 13443 | |6;480p.60fps.webm_scale_1_startup;4.5945941807044415;4.4917964071856264;4.274586894586895;4.298832378223497;4.367918486171762;4.208302945301541;4.444925925925927;4.274415954415956;4.519503012048194;4.638222565687791;4.464985119047618;4.19607692307692
23:53:19 INFO - PID 13443 | |7;480p.60fps.webm_scale_1_inclip;4.386524122807012;4.35838779956427;4.090572597137016;4.12430927835051;4.273301282051279;4.082173469387762;4.238559322033895;4.115288065843619;4.367063318777296;4.495629213483147;4.358507625272332;4.074765784114058
23:53:19 INFO - PID 13443 | |8;480p.60fps.webm_scale_1.1_startup;4.688390624999999;4.431846381093059;4.45803863298663;4.348811594202899;4.74004739336493;4.545780303030304;4.336047687861269;4.32335734870317;4.7169496855345905;4.658408385093172;4.658757763975157;4.37956204379562
23:53:19 INFO - PID 13443 | |9;480p.60fps.webm_scale_1.1_inclip;4.446366666666671;4.219978902953588;4.302182795698924;4.107402464065709;4.506193693693693;4.424845132743361;4.166624999999999;4.168114583333333;4.525576923076923;4.4344345898004445;4.416467991169978;4.159480249480247
23:53:19 INFO - PID 13443 | |10;480p.60fps.webm_scale_2_startup;4.838758064516127;4.958900826446278;4.823826366559489;4.809110576923074;4.777993630573251;4.793218849840256;4.855258899676374;4.97626036484245;5.008564273789645;4.832149758454105;4.8559385113268565;4.816709470304978
23:53:19 INFO - PID 13443 | |11;480p.60fps.webm_scale_2_inclip;4.653325581395349;4.751805225653203;4.652988372093023;4.620392609699771;4.598459770114946;4.621189376443414;4.642494199535965;4.797050359712228;4.820289156626511;4.641426914153136;4.63021990740741;4.63040509259259
23:53:19 INFO - PID 13443 | |12;1080p.60fps.mp4_scale_1_startup;7.212283653846148;7.110047393364934;7.1428333333333285;7.075978773584906;7.144321428571432;7.1783851674641115;7.093250591016539;7.1268527315914545;7.14286904761905;7.177404306220084;7.159785202863955;7.145083333333337
23:53:19 INFO - PID 13443 | |13;1080p.60fps.mp4_scale_1_inclip;6.759206081081076;6.7570439189189155;6.669916666666662;6.692140468227415;6.714848993288595;6.689096989966558;6.734629629629646;6.668916666666652;6.734225589225597;6.716006711409404;6.714412751677866;6.671866666666659
23:53:19 INFO - PID 13443 | |14;1080p.60fps.mp4_scale_1.1_startup;7.70007692307692;7.6547959183673475;7.7144601542416344;7.692948717948718;7.65413265306122;7.83321148825066;7.715115681233934;7.77436528497409;7.713791773778922;7.692487179487179;7.712763496143958;7.693089743589744
23:53:19 INFO - PID 13443 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.712583892617444;6.691973244147138;6.734410774410783;6.6897993311036785;6.646644518272423;6.802789115646248;6.66774999999999;6.690652173913035;6.691220735785938;6.689180602006672;6.733905723905727;6.7364814814814835
23:53:19 INFO - PID 13443 | |16;1080p.60fps.mp4_scale_2_startup;7.654145408163273;7.692320512820525;7.692358974358966;7.712699228791768;7.714730077120819;7.655561224489804;7.675396419437326;7.614936548223341;7.713097686375313;7.675332480818415;7.67265984654732;7.71262210796915
23:53:19 INFO - PID 13443 | |17;1080p.60fps.mp4_scale_2_inclip;6.734999999999994;6.734175084175069;6.691371237458198;6.689866220735775;6.692575250836126;6.6905016722408;6.713724832214773;6.736397306397303;6.669366666666659;6.69053511705686;6.692073578595319;6.7126342281879126
23:53:19 INFO - PID 13443 | __end_tp_report
23:53:19 INFO - PID 13443 | __start_cc_report
23:53:19 INFO - PID 13443 | _x_x_mozilla_cycle_collect,603
23:53:19 INFO - PID 13443 | __end_cc_report
23:53:19 INFO - PID 13443 | __startTimestamp1508482399002__endTimestamp
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | ------- Summary: start -------
23:53:19 INFO - PID 13443 | Number of tests: 18
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.65 Median:2.67 stddev:0.07 (2.5%) stddev-sans-first:0.01
23:53:19 INFO - PID 13443 | Values: 2.4 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7 2.7
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.42 Median:2.42 stddev:0.01 (0.4%) stddev-sans-first:0.01
23:53:19 INFO - PID 13443 | Values: 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4 2.4
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.52 Median:2.52 stddev:0.01 (0.2%) stddev-sans-first:0.01
23:53:19 INFO - PID 13443 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.45 Median:2.45 stddev:0.01 (0.3%) stddev-sans-first:0.01
23:53:19 INFO - PID 13443 | Values: 2.4 2.5 2.5 2.4 2.4 2.5 2.5 2.5 2.5 2.4 2.5 2.5
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.10 Median:3.10 stddev:0.01 (0.3%) stddev-sans-first:0.01
23:53:19 INFO - PID 13443 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.01 Median:3.01 stddev:0.00 (0.1%) stddev-sans-first:0.00
23:53:19 INFO - PID 13443 | Values: 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0 3.0
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.40 Median:4.45 stddev:0.15 (3.3%) stddev-sans-first:0.14
23:53:19 INFO - PID 13443 | Values: 4.6 4.5 4.3 4.3 4.4 4.2 4.4 4.3 4.5 4.6 4.5 4.2
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.25 Median:4.32 stddev:0.15 (3.4%) stddev-sans-first:0.15
23:53:19 INFO - PID 13443 | Values: 4.4 4.4 4.1 4.1 4.3 4.1 4.2 4.1 4.4 4.5 4.4 4.1
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.52 Median:4.60 stddev:0.16 (3.5%) stddev-sans-first:0.16
23:53:19 INFO - PID 13443 | Values: 4.7 4.4 4.5 4.3 4.7 4.5 4.3 4.3 4.7 4.7 4.7 4.4
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.32 Median:4.42 stddev:0.15 (3.4%) stddev-sans-first:0.15
23:53:19 INFO - PID 13443 | Values: 4.4 4.2 4.3 4.1 4.5 4.4 4.2 4.2 4.5 4.4 4.4 4.2
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.86 Median:4.85 stddev:0.08 (1.6%) stddev-sans-first:0.08
23:53:19 INFO - PID 13443 | Values: 4.8 5.0 4.8 4.8 4.8 4.8 4.9 5.0 5.0 4.8 4.9 4.8
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.67 Median:4.65 stddev:0.07 (1.6%) stddev-sans-first:0.08
23:53:19 INFO - PID 13443 | Values: 4.7 4.8 4.7 4.6 4.6 4.6 4.6 4.8 4.8 4.6 4.6 4.6
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.14 Median:7.14 stddev:0.04 (0.5%) stddev-sans-first:0.03
23:53:19 INFO - PID 13443 | Values: 7.2 7.1 7.1 7.1 7.1 7.2 7.1 7.1 7.1 7.2 7.2 7.1
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.71 Median:6.72 stddev:0.03 (0.5%) stddev-sans-first:0.03
23:53:19 INFO - PID 13443 | Values: 6.8 6.8 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.71 Median:7.71 stddev:0.05 (0.6%) stddev-sans-first:0.05
23:53:19 INFO - PID 13443 | Values: 7.7 7.7 7.7 7.7 7.7 7.8 7.7 7.8 7.7 7.7 7.7 7.7
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.71 Median:6.70 stddev:0.04 (0.6%) stddev-sans-first:0.04
23:53:19 INFO - PID 13443 | Values: 6.7 6.7 6.7 6.7 6.6 6.8 6.7 6.7 6.7 6.7 6.7 6.7
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.68 Median:7.69 stddev:0.03 (0.4%) stddev-sans-first:0.03
23:53:19 INFO - PID 13443 | Values: 7.7 7.7 7.7 7.7 7.7 7.7 7.7 7.6 7.7 7.7 7.7 7.7
23:53:19 INFO - PID 13443 |
23:53:19 INFO - PID 13443 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.70 Median:6.70 stddev:0.02 (0.3%) stddev-sans-first:0.02
23:53:19 INFO - PID 13443 | Values: 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7 6.7
23:53:19 INFO - PID 13443 | -------- Summary: end --------
23:53:19 INFO - PID 13443 |
23:53:19 INFO - TEST-INFO | 13443: exit 0
23:53:19 INFO - TEST-OK | basic_compositor_video | took 559925ms
23:53:19 INFO - TEST-START | glvideo
23:53:19 INFO - Initialising browser for glvideo test...
23:53:19 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:36410/getInfo.html -profile /tmp/tmpCfw1on/profile
23:53:19 INFO - TEST-INFO | started process 14701 (/builds/slave/test/build/application/firefox/firefox http://localhost:36410/getInfo.html)
23:53:26 INFO - TEST-INFO | 14701: exit 0
23:53:26 INFO - Browser initialized.
23:53:26 INFO - Running cycle 1/1 for glvideo test...
23:53:26 INFO - Using env: {'DISPLAY': ':0',
23:53:26 INFO - 'HOME': '/home/cltbld',
23:53:26 INFO - 'JSGC_DISABLE_POISONING': '1',
23:53:26 INFO - 'LANG': 'en_US.UTF-8',
23:53:26 INFO - 'LANGUAGE': 'en_US:en',
23:53:26 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
23:53:26 INFO - 'LOGNAME': 'cltbld',
23:53:26 INFO - 'MAIL': '/var/mail/cltbld',
23:53:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
23:53:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
23:53:26 INFO - 'MOZ_ACCELERATED': '1',
23:53:26 INFO - 'MOZ_CRASHREPORTER': '1',
23:53:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
23:53:26 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
23:53:26 INFO - 'MOZ_NO_REMOTE': '1',
23:53:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
23:53:26 INFO - 'MOZ_WEBRENDER': '1',
23:53:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
23:53:26 INFO - 'NO_EM_RESTART': '1',
23:53:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
23:53:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
23:53:26 INFO - 'PWD': '/builds/slave/test',
23:53:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
23:53:26 INFO - 'RUST_BACKTRACE': 'full',
23:53:26 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
23:53:26 INFO - 'SHELL': '/bin/bash',
23:53:26 INFO - 'SHLVL': '1',
23:53:26 INFO - 'TERM': 'linux',
23:53:26 INFO - 'TMOUT': '86400',
23:53:26 INFO - 'USER': 'cltbld',
23:53:26 INFO - 'XDG_SESSION_COOKIE': '8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592',
23:53:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
23:53:26 INFO - '_': '/tools/buildbot/bin/python'}
23:53:26 INFO - TEST-INFO | started process 14985 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCfw1on/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
23:53:27 INFO - PID 14985 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
23:53:27 INFO - PID 14985 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
23:53:27 INFO - PID 14985 |
23:53:27 INFO - PID 14985 | (/builds/slave/test/build/application/firefox/firefox:15048): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
23:53:27 INFO - PID 14985 |
23:53:28 INFO - PID 14985 | [talos glvideo result] Mean tick time across 100 ticks: 6.76155 ms
23:53:28 INFO - PID 14985 | Cycle 1(1): loaded http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html)
23:53:30 INFO - PID 14985 | [talos glvideo result] Mean tick time across 100 ticks: 6.513600000000001 ms
23:53:30 INFO - PID 14985 | Cycle 1(2): loaded http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html)
23:53:31 INFO - PID 14985 | [talos glvideo result] Mean tick time across 100 ticks: 6.466550000000001 ms
23:53:31 INFO - PID 14985 | Cycle 1(3): loaded http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html)
23:53:32 INFO - PID 14985 | [talos glvideo result] Mean tick time across 100 ticks: 6.57935 ms
23:53:32 INFO - PID 14985 | Cycle 1(4): loaded http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html)
23:53:34 INFO - PID 14985 | [talos glvideo result] Mean tick time across 100 ticks: 6.5899 ms
23:53:34 INFO - PID 14985 | Cycle 1(5): loaded http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:36410/tests/webgl/benchmarks/video/video_upload.html)
23:53:34 INFO - PID 14985 | __start_tp_report
23:53:34 INFO - PID 14985 | _x_x_mozilla_page_load
23:53:34 INFO - PID 14985 | _x_x_mozilla_page_load_details
23:53:34 INFO - PID 14985 | |i|pagename|runs|
23:53:34 INFO - PID 14985 | |0;Mean tick time across 100 ticks: ;6.76155;6.513600000000001;6.466550000000001;6.57935;6.5899
23:53:34 INFO - PID 14985 | __end_tp_report
23:53:34 INFO - PID 14985 | __start_cc_report
23:53:34 INFO - PID 14985 | _x_x_mozilla_cycle_collect,283
23:53:34 INFO - PID 14985 | __end_cc_report
23:53:34 INFO - PID 14985 | __startTimestamp1508482414424__endTimestamp
23:53:34 INFO - PID 14985 |
23:53:34 INFO - PID 14985 | ------- Summary: start -------
23:53:34 INFO - PID 14985 | Number of tests: 1
23:53:34 INFO - PID 14985 |
23:53:34 INFO - PID 14985 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.58 Median:6.58 stddev:0.11 (1.7%) stddev-sans-first:0.06
23:53:34 INFO - PID 14985 | Values: 6.8 6.5 6.5 6.6 6.6
23:53:34 INFO - PID 14985 | -------- Summary: end --------
23:53:34 INFO - PID 14985 |
23:53:34 INFO - PID 14985 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
23:53:35 INFO - TEST-INFO | 14985: exit 0
23:53:35 INFO - TEST-OK | glvideo | took 15268ms
23:53:35 INFO - SUITE-END | took 575s
23:53:35 INFO - Completed test suite (00:09:35)
23:53:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.464517598380535, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.445562347188264, 2.6743048128342246, 2.6672977777777778, 2.6691592526690395, 2.6507685512367494, 2.690865470852018, 2.6770695807314895, 2.6674088888888887, 2.676561106155219, 2.674590017825312, 2.669581850533808, 2.6866830796777084], "value": 2.6743048128342246, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4219007263922516, 2.40687725631769, 2.427876213592233, 2.4107771084337344, 2.410644578313253, 2.401314525810325, 2.430285540704739, 2.4282584951456307, 2.4164371980676322, 2.413504221954162, 2.413516284680338, 2.422524213075061], "value": 2.413516284680338, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.521172268907563, 2.516933724832215, 2.5257154882154884, 2.517437080536913, 2.5256818181818184, 2.5189294710327457, 2.5090928093645486, 2.523280067283431, 2.526022727272727, 2.5231833473507144, 2.5170343959731545, 2.525425084175084], "value": 2.5231833473507144, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.4389207317073183, 2.4516360294117647, 2.4511397058823543, 2.442527472527473, 2.442539682539682, 2.460922509225093, 2.451164215686275, 2.4581265356265356, 2.4546625766871157, 2.444981662591688, 2.4572665847665838, 2.4520098039215688], "value": 2.4516360294117647, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.115295950155764, 3.108787564766839, 3.109471502590673, 3.096522187822496, 3.0932783505154635, 3.090623069001029, 3.087294238683128, 3.0968317853457172, 3.0994576446280986, 3.0969091847265227, 3.0868621399176956, 3.1089170984455974], "value": 3.0968317853457172, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.0074887218045108, 3.0165460030165905, 3.0086541353383445, 3.007563909774437, 3.007654135338346, 3.0130948795180745, 3.017518853695324, 3.0120105421686754, 3.0087593984962417, 3.008729323308271, 3.016968325791855, 3.004204204204203], "value": 3.0087593984962417, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.5945941807044415, 4.4917964071856264, 4.274586894586895, 4.298832378223497, 4.367918486171762, 4.208302945301541, 4.444925925925927, 4.274415954415956, 4.519503012048194, 4.638222565687791, 4.464985119047618, 4.19607692307692], "value": 4.367918486171762, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.386524122807012, 4.35838779956427, 4.090572597137016, 4.12430927835051, 4.273301282051279, 4.082173469387762, 4.238559322033895, 4.115288065843619, 4.367063318777296, 4.495629213483147, 4.358507625272332, 4.074765784114058], "value": 4.238559322033895, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.688390624999999, 4.431846381093059, 4.45803863298663, 4.348811594202899, 4.74004739336493, 4.545780303030304, 4.336047687861269, 4.32335734870317, 4.7169496855345905, 4.658408385093172, 4.658757763975157, 4.37956204379562], "value": 4.45803863298663, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.446366666666671, 4.219978902953588, 4.302182795698924, 4.107402464065709, 4.506193693693693, 4.424845132743361, 4.166624999999999, 4.168114583333333, 4.525576923076923, 4.4344345898004445, 4.416467991169978, 4.159480249480247], "value": 4.302182795698924, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.838758064516127, 4.958900826446278, 4.823826366559489, 4.809110576923074, 4.777993630573251, 4.793218849840256, 4.855258899676374, 4.97626036484245, 5.008564273789645, 4.832149758454105, 4.8559385113268565, 4.816709470304978], "value": 4.832149758454105, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.653325581395349, 4.751805225653203, 4.652988372093023, 4.620392609699771, 4.598459770114946, 4.621189376443414, 4.642494199535965, 4.797050359712228, 4.820289156626511, 4.641426914153136, 4.63021990740741, 4.63040509259259], "value": 4.641426914153136, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.212283653846148, 7.110047393364934, 7.1428333333333285, 7.075978773584906, 7.144321428571432, 7.1783851674641115, 7.093250591016539, 7.1268527315914545, 7.14286904761905, 7.177404306220084, 7.159785202863955, 7.145083333333337], "value": 7.14286904761905, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.759206081081076, 6.7570439189189155, 6.669916666666662, 6.692140468227415, 6.714848993288595, 6.689096989966558, 6.734629629629646, 6.668916666666652, 6.734225589225597, 6.716006711409404, 6.714412751677866, 6.671866666666659], "value": 6.714412751677866, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.70007692307692, 7.6547959183673475, 7.7144601542416344, 7.692948717948718, 7.65413265306122, 7.83321148825066, 7.715115681233934, 7.77436528497409, 7.713791773778922, 7.692487179487179, 7.712763496143958, 7.693089743589744], "value": 7.712763496143958, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.712583892617444, 6.691973244147138, 6.734410774410783, 6.6897993311036785, 6.646644518272423, 6.802789115646248, 6.66774999999999, 6.690652173913035, 6.691220735785938, 6.689180602006672, 6.733905723905727, 6.7364814814814835], "value": 6.691220735785938, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.654145408163273, 7.692320512820525, 7.692358974358966, 7.712699228791768, 7.714730077120819, 7.655561224489804, 7.675396419437326, 7.614936548223341, 7.713097686375313, 7.675332480818415, 7.67265984654732, 7.71262210796915], "value": 7.692320512820525, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.734999999999994, 6.734175084175069, 6.691371237458198, 6.689866220735775, 6.692575250836126, 6.6905016722408, 6.713724832214773, 6.736397306397303, 6.669366666666659, 6.69053511705686, 6.692073578595319, 6.7126342281879126], "value": 6.692073578595319, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.76155, 6.513600000000001, 6.466550000000001, 6.57935, 6.5899], "value": 6.546475000000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}]}
23:53:35 INFO - Return code: 0
23:53:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
23:53:35 INFO - # TBPL SUCCESS #
23:53:35 INFO - Running post-action listener: _package_coverage_data
23:53:35 INFO - Running post-action listener: _resource_record_post_action
23:53:35 INFO - [mozharness: 2017-10-20 06:53:35.566873Z] Finished run-tests step (success)
23:53:35 INFO - Running post-run listener: _resource_record_post_run
23:53:35 INFO - Total resource usage - Wall time: 595s; CPU: 48.0%; Read bytes: 2809856; Write bytes: 439046144; Read time: 276; Write time: 338000
23:53:35 INFO - TinderboxPrint: CPU usage
48.4%
23:53:35 INFO - TinderboxPrint: I/O read bytes / time
2,809,856 / 276
23:53:35 INFO - TinderboxPrint: I/O write bytes / time
439,046,144 / 338,000
23:53:35 INFO - TinderboxPrint: CPU idle
2,352.9 (51.7%)
23:53:35 INFO - TinderboxPrint: CPU system
729.4 (16.0%)
23:53:35 INFO - TinderboxPrint: CPU user
1,448.6 (31.8%)
23:53:35 INFO - TinderboxPrint: Swap in / out
0 / 0
23:53:35 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 126996480; Read time: 0; Write time: 124180
23:53:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
23:53:35 INFO - run-tests - Wall time: 576s; CPU: 50.0%; Read bytes: 2809856; Write bytes: 310779904; Read time: 276; Write time: 213632
23:53:35 INFO - Running post-run listener: _upload_blobber_files
23:53:35 INFO - Blob upload gear active.
23:53:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
23:53:35 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
23:53:35 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']
23:53:35 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
23:53:36 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
23:53:36 INFO - SNIMissingWarning
23:53:36 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
23:53:36 INFO - InsecurePlatformWarning
23:53:36 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.)
23:53:36 INFO - SubjectAltNameWarning
23:53:36 INFO - (blobuploader) - INFO - Open directory for files ...
23:53:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
23:53:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
23:53:36 INFO - (blobuploader) - INFO - Uploading, attempt #1.
23:53:36 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
23:53:36 INFO - InsecurePlatformWarning
23:53:36 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.)
23:53:36 INFO - SubjectAltNameWarning
23:53:36 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
23:53:36 INFO - InsecurePlatformWarning
23:53:37 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
23:53:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
23:53:37 INFO - (blobuploader) - INFO - Done attempting.
23:53:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
23:53:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
23:53:37 INFO - (blobuploader) - INFO - Uploading, attempt #1.
23:53:37 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
23:53:37 INFO - InsecurePlatformWarning
23:53:37 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.)
23:53:37 INFO - SubjectAltNameWarning
23:53:37 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
23:53:37 INFO - InsecurePlatformWarning
23:53:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
23:53:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
23:53:38 INFO - (blobuploader) - INFO - Done attempting.
23:53:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
23:53:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
23:53:38 INFO - (blobuploader) - INFO - Uploading, attempt #1.
23:53:38 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
23:53:38 INFO - InsecurePlatformWarning
23:53:38 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.)
23:53:38 INFO - SubjectAltNameWarning
23:53:42 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
23:53:42 INFO - InsecurePlatformWarning
23:53:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
23:53:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
23:53:43 INFO - (blobuploader) - INFO - Done attempting.
23:53:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
23:53:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
23:53:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
23:53:43 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
23:53:43 INFO - InsecurePlatformWarning
23:53:43 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.)
23:53:43 INFO - SubjectAltNameWarning
23:53:43 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
23:53:43 INFO - InsecurePlatformWarning
23:53:44 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
23:53:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
23:53:44 INFO - (blobuploader) - INFO - Done attempting.
23:53:44 INFO - (blobuploader) - INFO - Iteration through files over.
23:53:44 INFO - Return code: 0
23:53:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
23:53:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
23:53:44 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1e08a60c10d30ab3a3698d40b360691a4ecca669072345f8e43d224f58c45772ffc2c8b234f5e4f1a16eaf66e3902818ba523878e0017808d0226334668c901", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dbcfbf20d37e0f63ce946def3eeacc0ec527602d3d0b5676aa698f53034e29e6b5413f97d83885d3b4c6971027134ecc94d3b33c5c54e187ecd7c81eb402330", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14c4874c4ec7a209228e6d3ba8d23d5d643cc91a96d4538cd753d6bc397708062bc3e3b20d37c9619517e02e3fa619eb8b1ba957c33a4e54bd618199450665cf", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a887b83b95f41e3318d13e8bc05d5793501ca9b9eca53692f328c68ca3715202905586df240bac9b32a6a003071a5da4f4cd07f6f48ca10bc7c433d72d72e759"}
23:53:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
23:53:44 INFO - Writing to file /builds/slave/test/properties/blobber_files
23:53:44 INFO - Contents:
23:53:44 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1e08a60c10d30ab3a3698d40b360691a4ecca669072345f8e43d224f58c45772ffc2c8b234f5e4f1a16eaf66e3902818ba523878e0017808d0226334668c901", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dbcfbf20d37e0f63ce946def3eeacc0ec527602d3d0b5676aa698f53034e29e6b5413f97d83885d3b4c6971027134ecc94d3b33c5c54e187ecd7c81eb402330", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14c4874c4ec7a209228e6d3ba8d23d5d643cc91a96d4538cd753d6bc397708062bc3e3b20d37c9619517e02e3fa619eb8b1ba957c33a4e54bd618199450665cf", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a887b83b95f41e3318d13e8bc05d5793501ca9b9eca53692f328c68ca3715202905586df240bac9b32a6a003071a5da4f4cd07f6f48ca10bc7c433d72d72e759"}
23:53:44 INFO - Running post-run listener: copy_logs_to_upload_dir
23:53:44 INFO - Copying logs to upload dir...
23:53:44 INFO - mkdir: /builds/slave/test/build/upload/logs
23:53:44 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=656.452499
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 56 secs) (at 2017-10-19 23:53:44.147126) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-19 23:53:44.150702) =========
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=8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592
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/a1e08a60c10d30ab3a3698d40b360691a4ecca669072345f8e43d224f58c45772ffc2c8b234f5e4f1a16eaf66e3902818ba523878e0017808d0226334668c901", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dbcfbf20d37e0f63ce946def3eeacc0ec527602d3d0b5676aa698f53034e29e6b5413f97d83885d3b4c6971027134ecc94d3b33c5c54e187ecd7c81eb402330", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14c4874c4ec7a209228e6d3ba8d23d5d643cc91a96d4538cd753d6bc397708062bc3e3b20d37c9619517e02e3fa619eb8b1ba957c33a4e54bd618199450665cf", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a887b83b95f41e3318d13e8bc05d5793501ca9b9eca53692f328c68ca3715202905586df240bac9b32a6a003071a5da4f4cd07f6f48ca10bc7c433d72d72e759"}
build_url:https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011347
build_url: 'https://queue.taskcluster.net/v1/task/LUX-7JAQS26AWNdzpZhYIw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a1e08a60c10d30ab3a3698d40b360691a4ecca669072345f8e43d224f58c45772ffc2c8b234f5e4f1a16eaf66e3902818ba523878e0017808d0226334668c901", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2dbcfbf20d37e0f63ce946def3eeacc0ec527602d3d0b5676aa698f53034e29e6b5413f97d83885d3b4c6971027134ecc94d3b33c5c54e187ecd7c81eb402330", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/14c4874c4ec7a209228e6d3ba8d23d5d643cc91a96d4538cd753d6bc397708062bc3e3b20d37c9619517e02e3fa619eb8b1ba957c33a4e54bd618199450665cf", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a887b83b95f41e3318d13e8bc05d5793501ca9b9eca53692f328c68ca3715202905586df240bac9b32a6a003071a5da4f4cd07f6f48ca10bc7c433d72d72e759"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-19 23:53:44.180382) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:53:44.180698) =========
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=8ec0ee4e6a493f968516bea6000001a5-1508480338.981334-304732592
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.005036
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-19 23:53:44.264373) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-19 23:53:44.264763) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-19 23:53:44.265054) =========
========= Total master_lag: 0.22 =========