builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-032
starttime: 1510338868.11
results: success (0)
revision: b0ded9bbd517b1cef61beef4245323d1441fedc3
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.106337) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.106753) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.107017) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.126035) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.126350) =========
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=745a4373a2a16df560f216e000000264-1510338867.177146-1985830882
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-10 10:34:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 46.5M=0s
2017-11-10 10:34:28 (46.5 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.237559
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.385348) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.385837) =========
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=745a4373a2a16df560f216e000000264-1510338867.177146-1985830882
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.017872
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:28.437096) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-10 10:34:28.437598) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b0ded9bbd517b1cef61beef4245323d1441fedc3 --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 b0ded9bbd517b1cef61beef4245323d1441fedc3 --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=745a4373a2a16df560f216e000000264-1510338867.177146-1985830882
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-10 10:34:28,493 truncating revision to first 12 chars
2017-11-10 10:34:28,493 Setting DEBUG logging.
2017-11-10 10:34:28,493 attempt 1/10
2017-11-10 10:34:28,493 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b0ded9bbd517?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-10 10:34:28,962 unpacking tar archive at: mozilla-central-b0ded9bbd517/testing/mozharness/
program finished with exit code 0
elapsedTime=1.115243
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-10 10:34:29.570868) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:29.571175) =========
script_repo_revision: b0ded9bbd517b1cef61beef4245323d1441fedc3
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:29.571581) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:29.571837) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-10 10:34:29.585837) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 49 secs) (at 2017-11-10 10:34:29.586246) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1510338867.177146-1985830882
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
10:34:29 INFO - MultiFileLogger online at 20171110 10:34:29 in /builds/slave/test
10:34:29 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
10:34:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
10:34:29 INFO - {'append_to_log': False,
10:34:29 INFO - 'base_work_dir': '/builds/slave/test',
10:34:29 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
10:34:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
10:34:29 INFO - 'branch': 'Firefox-Non-PGO',
10:34:29 INFO - 'buildbot_json_path': 'buildprops.json',
10:34:29 INFO - 'code_coverage': False,
10:34:29 INFO - 'config_files': ('talos/linux_config.py',),
10:34:29 INFO - 'default_actions': ('clobber',
10:34:29 INFO - 'read-buildbot-config',
10:34:29 INFO - 'download-and-extract',
10:34:29 INFO - 'populate-webroot',
10:34:29 INFO - 'create-virtualenv',
10:34:29 INFO - 'install',
10:34:29 INFO - 'setup-mitmproxy',
10:34:29 INFO - 'run-tests'),
10:34:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
10:34:29 INFO - 'disable_ccov_upload': False,
10:34:29 INFO - 'disable_stylo': False,
10:34:29 INFO - 'download_minidump_stackwalk': True,
10:34:29 INFO - 'download_symbols': 'ondemand',
10:34:29 INFO - 'e10s': False,
10:34:29 INFO - 'enable_stylo': False,
10:34:29 INFO - 'enable_webrender': False,
10:34:29 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
10:34:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
10:34:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
10:34:29 INFO - 'gecko_profile': False,
10:34:29 INFO - 'gecko_profile_interval': 0,
10:34:29 INFO - 'installer_path': 'installer.exe',
10:34:29 INFO - 'jsd_code_coverage': False,
10:34:29 INFO - 'log_level': 'info',
10:34:29 INFO - 'log_name': 'talos',
10:34:29 INFO - 'log_to_console': True,
10:34:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
10:34:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
10:34:29 INFO - 'opt_config_files': (),
10:34:29 INFO - 'pip_index': False,
10:34:29 INFO - 'suite': 'g4-e10s',
10:34:29 INFO - 'system_bits': '32',
10:34:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
10:34:29 INFO - 'title': 'talos-linux64-ix-032',
10:34:29 INFO - 'tooltool_cache': '/builds/tooltool_cache',
10:34:29 INFO - 'use_talos_json': True,
10:34:29 INFO - 'verify': 'False',
10:34:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
10:34:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
10:34:29 INFO - 'work_dir': 'build'}
10:34:29 INFO - [mozharness: 2017-11-10 18:34:29.778003Z] Running clobber step.
10:34:29 INFO - Running pre-action listener: _resource_record_pre_action
10:34:29 INFO - Running main action method: clobber
10:34:29 INFO - rmtree: /builds/slave/test/build
10:34:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
10:34:30 INFO - Running post-action listener: _resource_record_post_action
10:34:30 INFO - [mozharness: 2017-11-10 18:34:30.877853Z] Finished clobber step (success)
10:34:30 INFO - [mozharness: 2017-11-10 18:34:30.877951Z] Running read-buildbot-config step.
10:34:30 INFO - Running pre-action listener: _resource_record_pre_action
10:34:30 INFO - Running main action method: read_buildbot_config
10:34:30 INFO - Using buildbot properties:
10:34:30 INFO - {
10:34:30 INFO - "project": "",
10:34:30 INFO - "product": "firefox",
10:34:30 INFO - "who": "ryanvm@gmail.com",
10:34:30 INFO - "installer_path": "public/build/target.tar.bz2",
10:34:30 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
10:34:30 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
10:34:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
10:34:30 INFO - "stage_platform": "linux64",
10:34:30 INFO - "basedir": "/builds/slave/test",
10:34:30 INFO - "buildnumber": 91,
10:34:30 INFO - "platform": "ubuntu64_hw",
10:34:30 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
10:34:30 INFO - "slavebuilddir": "test",
10:34:30 INFO - "taskId": "azQfsnHAQcavfDesuf220Q",
10:34:30 INFO - "branch": "mozilla-central",
10:34:30 INFO - "script_repo_revision": "production",
10:34:30 INFO - "revision": "b0ded9bbd517b1cef61beef4245323d1441fedc3",
10:34:30 INFO - "slavename": "talos-linux64-ix-032",
10:34:30 INFO - "repo_path": "mozilla-central"
10:34:30 INFO - }
10:34:30 INFO - Finding installer, test and symbols from parent task.
10:34:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/azQfsnHAQcavfDesuf220Q'}, attempt #1
10:34:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/azQfsnHAQcavfDesuf220Q'}, attempt #1
10:34:31 INFO - Task dependencies: TSETt-vlSba2ChYGtbVCow
10:34:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow'}, attempt #1
10:34:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2
10:34:32 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.test_packages.json
10:34:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.crashreporter-symbols.zip
10:34:32 INFO - Running post-action listener: _resource_record_post_action
10:34:32 INFO - [mozharness: 2017-11-10 18:34:32.444871Z] Finished read-buildbot-config step (success)
10:34:32 INFO - [mozharness: 2017-11-10 18:34:32.445092Z] Running download-and-extract step.
10:34:32 INFO - Running pre-action listener: _resource_record_pre_action
10:34:32 INFO - Running main action method: download_and_extract
10:34:32 INFO - mkdir: /builds/slave/test/build/tests
10:34:32 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')]}
10:34:32 INFO - trying https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.test_packages.json
10:34:32 INFO - Downloading https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
10:34:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
10:34:33 INFO - Downloaded 989 bytes.
10:34:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
10:34:33 INFO - Using the following test package requirements:
10:34:33 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
10:34:33 INFO - u'common': [u'target.common.tests.zip'],
10:34:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
10:34:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
10:34:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
10:34:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
10:34:33 INFO - u'mozbase': [u'target.common.tests.zip'],
10:34:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
10:34:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
10:34:33 INFO - u'web-platform': [u'target.common.tests.zip',
10:34:33 INFO - u'target.web-platform.tests.tar.gz'],
10:34:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
10:34:33 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
10:34:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.common.tests.zip
10:34:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.common.tests.zip'}, attempt #1
10:34:33 INFO - Fetch https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.common.tests.zip into memory
10:34:34 INFO - Content-Length response header: 57791742
10:34:34 INFO - Bytes received: 57791742
10:34:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.talos.tests.zip
10:34:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.talos.tests.zip'}, attempt #1
10:34:45 INFO - Fetch https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.talos.tests.zip into memory
10:34:45 INFO - Content-Length response header: 18771918
10:34:45 INFO - Bytes received: 18771918
10:34:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:46 INFO - trying https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2
10:34:46 INFO - Downloading https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
10:34:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
10:34:47 INFO - Downloaded 62383975 bytes.
10:34:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2
10:34:47 INFO - mkdir: /builds/slave/test/properties
10:34:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
10:34:47 INFO - Writing to file /builds/slave/test/properties/build_url
10:34:47 INFO - Contents:
10:34:47 INFO - build_url:https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2
10:34:47 INFO - Running post-action listener: _resource_record_post_action
10:34:47 INFO - Running post-action listener: find_tests_for_verification
10:34:47 INFO - Running post-action listener: set_extra_try_arguments
10:34:47 INFO - [mozharness: 2017-11-10 18:34:47.646337Z] Finished download-and-extract step (success)
10:34:47 INFO - [mozharness: 2017-11-10 18:34:47.646488Z] Running populate-webroot step.
10:34:47 INFO - Running pre-action listener: _resource_record_pre_action
10:34:47 INFO - Running main action method: populate_webroot
10:34:47 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
10:34:47 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['tresize',
10:34:47 INFO - 'tcanvasmark']},
10:34:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
10:34:47 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['dromaeo_css',
10:34:47 INFO - 'kraken']},
10:34:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
10:34:47 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
10:34:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'tests': ['damp', 'tps']},
10:34:47 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['damp', 'tps']},
10:34:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
10:34:47 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['dromaeo_dom']},
10:34:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
10:34:47 INFO - 'glvideo',
10:34:47 INFO - 'displaylist_mutate']},
10:34:47 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['basic_compositor_video',
10:34:47 INFO - 'glvideo']},
10:34:47 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
10:34:47 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['ts_paint_webext',
10:34:47 INFO - 'tp5o_webext']},
10:34:47 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
10:34:47 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:47 INFO - 'talos_options': ['--mitmproxy',
10:34:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:47 INFO - '--firstNonBlankPaint'],
10:34:47 INFO - 'tests': ['tp6_google_heavy',
10:34:47 INFO - 'tp6_youtube_heavy',
10:34:47 INFO - 'tp6_amazon_heavy',
10:34:47 INFO - 'tp6_facebook_heavy']},
10:34:47 INFO - 'other-e10s': {'tests': ['a11yr',
10:34:47 INFO - 'ts_paint',
10:34:47 INFO - 'tpaint',
10:34:47 INFO - 'sessionrestore',
10:34:47 INFO - 'sessionrestore_many_windows',
10:34:47 INFO - 'sessionrestore_no_auto_restore',
10:34:47 INFO - 'tabpaint',
10:34:47 INFO - 'cpstartup']},
10:34:47 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['a11yr',
10:34:47 INFO - 'ts_paint',
10:34:47 INFO - 'tpaint',
10:34:47 INFO - 'sessionrestore',
10:34:47 INFO - 'sessionrestore_many_windows',
10:34:47 INFO - 'sessionrestore_no_auto_restore',
10:34:47 INFO - 'tabpaint',
10:34:47 INFO - 'cpstartup']},
10:34:47 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
10:34:47 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
10:34:47 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['perf_reftest_singletons']},
10:34:47 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['perf_reftest']},
10:34:47 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
10:34:47 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['speedometer']},
10:34:47 INFO - 'svgr-e10s': {'tests': ['tsvgx',
10:34:47 INFO - 'tsvgr_opacity',
10:34:47 INFO - 'tart',
10:34:47 INFO - 'tscrollx',
10:34:47 INFO - 'tsvg_static']},
10:34:47 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['tsvgx',
10:34:47 INFO - 'tsvgr_opacity',
10:34:47 INFO - 'tart',
10:34:47 INFO - 'tscrollx',
10:34:47 INFO - 'tsvg_static']},
10:34:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
10:34:47 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'talos_options': ['--disable-stylo'],
10:34:47 INFO - 'tests': ['tp5o']},
10:34:47 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:47 INFO - 'talos_options': ['--mitmproxy',
10:34:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:47 INFO - '--firstNonBlankPaint'],
10:34:47 INFO - 'tests': ['tp6_google',
10:34:47 INFO - 'tp6_youtube',
10:34:47 INFO - 'tp6_amazon',
10:34:47 INFO - 'tp6_facebook']},
10:34:47 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:47 INFO - 'talos_options': ['--disable-stylo',
10:34:47 INFO - '--mitmproxy',
10:34:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:47 INFO - '--firstNonBlankPaint'],
10:34:47 INFO - 'tests': ['tp6_google',
10:34:47 INFO - 'tp6_youtube',
10:34:47 INFO - 'tp6_amazon',
10:34:47 INFO - 'tp6_facebook']},
10:34:47 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:47 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:47 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:47 INFO - 'talos_options': ['--stylo-threads=1',
10:34:47 INFO - '--mitmproxy',
10:34:47 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:47 INFO - '--firstNonBlankPaint'],
10:34:47 INFO - 'tests': ['tp6_google',
10:34:47 INFO - 'tp6_youtube',
10:34:47 INFO - 'tp6_amazon',
10:34:47 INFO - 'tp6_facebook']},
10:34:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'talos_options': ['--xperf_path',
10:34:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:34:47 INFO - 'tests': ['tp5n']},
10:34:47 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:47 INFO - 'talos_options': ['--disable-stylo',
10:34:47 INFO - '--xperf_path',
10:34:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:34:47 INFO - 'tests': ['tp5n']}}}
10:34:47 INFO - Running post-action listener: _resource_record_post_action
10:34:47 INFO - [mozharness: 2017-11-10 18:34:47.665881Z] Finished populate-webroot step (success)
10:34:47 INFO - [mozharness: 2017-11-10 18:34:47.665974Z] Running create-virtualenv step.
10:34:47 INFO - Running pre-action listener: _resource_record_pre_action
10:34:47 INFO - Running main action method: create_virtualenv
10:34:47 INFO - Creating virtualenv /builds/slave/test/build/venv
10:34:47 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
10:34:47 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
10:34:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
10:34:48 INFO - Using real prefix '/usr'
10:34:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python
10:34:48 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
10:34:50 INFO - Installing setuptools, pip, wheel...done.
10:34:50 INFO - Return code: 0
10:34:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
10:34: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')]}
10:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34: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')]}
10:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:34:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
10:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
10:34:50 INFO - Using env: {'DISPLAY': ':0',
10:34:50 INFO - 'HOME': '/home/cltbld',
10:34:50 INFO - 'LANG': 'en_US.UTF-8',
10:34:50 INFO - 'LANGUAGE': 'en_US:en',
10:34:50 INFO - 'LOGNAME': 'cltbld',
10:34:50 INFO - 'MAIL': '/var/mail/cltbld',
10:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:50 INFO - 'MOZ_NO_REMOTE': '1',
10:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:50 INFO - 'NO_EM_RESTART': '1',
10:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:50 INFO - 'PWD': '/builds/slave/test',
10:34:50 INFO - 'SHELL': '/bin/bash',
10:34:50 INFO - 'SHLVL': '1',
10:34:50 INFO - 'TERM': 'linux',
10:34:50 INFO - 'TMOUT': '86400',
10:34:50 INFO - 'USER': 'cltbld',
10:34:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:50 INFO - '_': '/tools/buildbot/bin/python'}
10:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
10:34:50 INFO - Return code: 0
10:34:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
10:34: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')]}
10:34:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34: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')]}
10:34:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:34:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
10:34:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
10:34:50 INFO - Using env: {'DISPLAY': ':0',
10:34:50 INFO - 'HOME': '/home/cltbld',
10:34:50 INFO - 'LANG': 'en_US.UTF-8',
10:34:50 INFO - 'LANGUAGE': 'en_US:en',
10:34:50 INFO - 'LOGNAME': 'cltbld',
10:34:50 INFO - 'MAIL': '/var/mail/cltbld',
10:34:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:50 INFO - 'MOZ_NO_REMOTE': '1',
10:34:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:50 INFO - 'NO_EM_RESTART': '1',
10:34:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:50 INFO - 'PWD': '/builds/slave/test',
10:34:50 INFO - 'SHELL': '/bin/bash',
10:34:50 INFO - 'SHLVL': '1',
10:34:50 INFO - 'TERM': 'linux',
10:34:50 INFO - 'TMOUT': '86400',
10:34:50 INFO - 'USER': 'cltbld',
10:34:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:34:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:50 INFO - '_': '/tools/buildbot/bin/python'}
10:34:50 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:50 INFO - Collecting psutil>=3.1.1
10:34:52 INFO - Installing collected packages: psutil
10:34:52 INFO - Successfully installed psutil-3.1.1
10:34:52 INFO - Return code: 0
10:34:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
10:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:34:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
10:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
10:34:52 INFO - Using env: {'DISPLAY': ':0',
10:34:52 INFO - 'HOME': '/home/cltbld',
10:34:52 INFO - 'LANG': 'en_US.UTF-8',
10:34:52 INFO - 'LANGUAGE': 'en_US:en',
10:34:52 INFO - 'LOGNAME': 'cltbld',
10:34:52 INFO - 'MAIL': '/var/mail/cltbld',
10:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:52 INFO - 'MOZ_NO_REMOTE': '1',
10:34:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:52 INFO - 'NO_EM_RESTART': '1',
10:34:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:52 INFO - 'PWD': '/builds/slave/test',
10:34:52 INFO - 'SHELL': '/bin/bash',
10:34:52 INFO - 'SHLVL': '1',
10:34:52 INFO - 'TERM': 'linux',
10:34:52 INFO - 'TMOUT': '86400',
10:34:52 INFO - 'USER': 'cltbld',
10:34:52 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:52 INFO - '_': '/tools/buildbot/bin/python'}
10:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:52 INFO - Collecting mozsystemmonitor==0.3
10:34:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
10:34:53 INFO - Installing collected packages: mozsystemmonitor
10:34:53 INFO - Successfully installed mozsystemmonitor-0.3
10:34:53 INFO - Return code: 0
10:34:53 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
10:34:53 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')]}
10:34:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:53 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')]}
10:34:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:53 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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:34:53 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
10:34:53 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
10:34:53 INFO - Using env: {'DISPLAY': ':0',
10:34:53 INFO - 'HOME': '/home/cltbld',
10:34:53 INFO - 'LANG': 'en_US.UTF-8',
10:34:53 INFO - 'LANGUAGE': 'en_US:en',
10:34:53 INFO - 'LOGNAME': 'cltbld',
10:34:53 INFO - 'MAIL': '/var/mail/cltbld',
10:34:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:53 INFO - 'MOZ_NO_REMOTE': '1',
10:34:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:53 INFO - 'NO_EM_RESTART': '1',
10:34:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:53 INFO - 'PWD': '/builds/slave/test',
10:34:53 INFO - 'SHELL': '/bin/bash',
10:34:53 INFO - 'SHLVL': '1',
10:34:53 INFO - 'TERM': 'linux',
10:34:53 INFO - 'TMOUT': '86400',
10:34:53 INFO - 'USER': 'cltbld',
10:34:53 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:34:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:53 INFO - '_': '/tools/buildbot/bin/python'}
10:34:54 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:54 INFO - Collecting jsonschema==2.5.1
10:34:55 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
10:34:55 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
10:34:56 INFO - Installing collected packages: functools32, jsonschema
10:34:56 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
10:34:56 INFO - Return code: 0
10:34:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
10:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
10:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
10:34:56 INFO - Using env: {'DISPLAY': ':0',
10:34:56 INFO - 'HOME': '/home/cltbld',
10:34:56 INFO - 'LANG': 'en_US.UTF-8',
10:34:56 INFO - 'LANGUAGE': 'en_US:en',
10:34:56 INFO - 'LOGNAME': 'cltbld',
10:34:56 INFO - 'MAIL': '/var/mail/cltbld',
10:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:56 INFO - 'MOZ_NO_REMOTE': '1',
10:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:56 INFO - 'NO_EM_RESTART': '1',
10:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:56 INFO - 'PWD': '/builds/slave/test',
10:34:56 INFO - 'SHELL': '/bin/bash',
10:34:56 INFO - 'SHLVL': '1',
10:34:56 INFO - 'TERM': 'linux',
10:34:56 INFO - 'TMOUT': '86400',
10:34:56 INFO - 'USER': 'cltbld',
10:34:56 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:56 INFO - '_': '/tools/buildbot/bin/python'}
10:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
10:34:57 INFO - Return code: 0
10:34:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
10:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:57 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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:34:57 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
10:34:57 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
10:34:57 INFO - Using env: {'DISPLAY': ':0',
10:34:57 INFO - 'HOME': '/home/cltbld',
10:34:57 INFO - 'LANG': 'en_US.UTF-8',
10:34:57 INFO - 'LANGUAGE': 'en_US:en',
10:34:57 INFO - 'LOGNAME': 'cltbld',
10:34:57 INFO - 'MAIL': '/var/mail/cltbld',
10:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:57 INFO - 'MOZ_NO_REMOTE': '1',
10:34:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:57 INFO - 'NO_EM_RESTART': '1',
10:34:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:57 INFO - 'PWD': '/builds/slave/test',
10:34:57 INFO - 'SHELL': '/bin/bash',
10:34:57 INFO - 'SHLVL': '1',
10:34:57 INFO - 'TERM': 'linux',
10:34:57 INFO - 'TMOUT': '86400',
10:34:57 INFO - 'USER': 'cltbld',
10:34:57 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:57 INFO - '_': '/tools/buildbot/bin/python'}
10:34:58 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:58 INFO - Collecting blobuploader==1.2.4
10:34:59 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
10:35:00 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
10:35:01 INFO - Installing collected packages: requests, docopt, blobuploader
10:35:01 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
10:35:01 INFO - Return code: 0
10:35:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv
10:35:01 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')]}
10:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:35:01 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')]}
10:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:35:01 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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:35:01 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
10:35:01 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
10:35:01 INFO - Using env: {'DISPLAY': ':0',
10:35:01 INFO - 'HOME': '/home/cltbld',
10:35:01 INFO - 'LANG': 'en_US.UTF-8',
10:35:01 INFO - 'LANGUAGE': 'en_US:en',
10:35:01 INFO - 'LOGNAME': 'cltbld',
10:35:01 INFO - 'MAIL': '/var/mail/cltbld',
10:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:01 INFO - 'MOZ_NO_REMOTE': '1',
10:35:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:01 INFO - 'NO_EM_RESTART': '1',
10:35:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:01 INFO - 'PWD': '/builds/slave/test',
10:35:01 INFO - 'SHELL': '/bin/bash',
10:35:01 INFO - 'SHLVL': '1',
10:35:01 INFO - 'TERM': 'linux',
10:35:01 INFO - 'TMOUT': '86400',
10:35:01 INFO - 'USER': 'cltbld',
10:35:01 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:01 INFO - '_': '/tools/buildbot/bin/python'}
10:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple
10:35:01 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
10:35:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
10:35:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
10:35:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
10:35:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
10:35:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
10:35:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
10:35:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
10:35:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
10:35:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
10:35:03 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
10:35:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
10:35:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
10:35:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
10:35:04 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
10:35:04 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
10:35:05 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
10:35:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
10:35:05 INFO - Running setup.py install for manifestparser: started
10:35:05 INFO - Running setup.py install for manifestparser: finished with status 'done'
10:35:05 INFO - Running setup.py install for mozcrash: started
10:35:05 INFO - Running setup.py install for mozcrash: finished with status 'done'
10:35:05 INFO - Running setup.py install for mozdebug: started
10:35:05 INFO - Running setup.py install for mozdebug: finished with status 'done'
10:35:05 INFO - Running setup.py install for mozdevice: started
10:35:06 INFO - Running setup.py install for mozdevice: finished with status 'done'
10:35:06 INFO - Running setup.py install for mozfile: started
10:35:06 INFO - Running setup.py install for mozfile: finished with status 'done'
10:35:06 INFO - Running setup.py install for mozhttpd: started
10:35:06 INFO - Running setup.py install for mozhttpd: finished with status 'done'
10:35:06 INFO - Running setup.py install for mozinfo: started
10:35:06 INFO - Running setup.py install for mozinfo: finished with status 'done'
10:35:06 INFO - Running setup.py install for mozInstall: started
10:35:06 INFO - Running setup.py install for mozInstall: finished with status 'done'
10:35:06 INFO - Running setup.py install for mozleak: started
10:35:07 INFO - Running setup.py install for mozleak: finished with status 'done'
10:35:07 INFO - Running setup.py install for mozlog: started
10:35:07 INFO - Running setup.py install for mozlog: finished with status 'done'
10:35:07 INFO - Running setup.py install for moznetwork: started
10:35:07 INFO - Running setup.py install for moznetwork: finished with status 'done'
10:35:07 INFO - Running setup.py install for mozprocess: started
10:35:07 INFO - Running setup.py install for mozprocess: finished with status 'done'
10:35:07 INFO - Running setup.py install for mozprofile: started
10:35:08 INFO - Running setup.py install for mozprofile: finished with status 'done'
10:35:08 INFO - Running setup.py install for mozrunner: started
10:35:08 INFO - Running setup.py install for mozrunner: finished with status 'done'
10:35:08 INFO - Running setup.py install for mozscreenshot: started
10:35:08 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
10:35:08 INFO - Running setup.py install for moztest: started
10:35:08 INFO - Running setup.py install for moztest: finished with status 'done'
10:35:08 INFO - Running setup.py install for mozversion: started
10:35:08 INFO - Running setup.py install for mozversion: finished with status 'done'
10:35:09 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
10:35:09 INFO - Return code: 0
10:35:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv
10:35:09 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')]}
10:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:35:09 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')]}
10:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:35:09 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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:35:09 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
10:35:09 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
10:35:09 INFO - Using env: {'DISPLAY': ':0',
10:35:09 INFO - 'HOME': '/home/cltbld',
10:35:09 INFO - 'LANG': 'en_US.UTF-8',
10:35:09 INFO - 'LANGUAGE': 'en_US:en',
10:35:09 INFO - 'LOGNAME': 'cltbld',
10:35:09 INFO - 'MAIL': '/var/mail/cltbld',
10:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:09 INFO - 'MOZ_NO_REMOTE': '1',
10:35:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:09 INFO - 'NO_EM_RESTART': '1',
10:35:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:09 INFO - 'PWD': '/builds/slave/test',
10:35:09 INFO - 'SHELL': '/bin/bash',
10:35:09 INFO - 'SHLVL': '1',
10:35:09 INFO - 'TERM': 'linux',
10:35:09 INFO - 'TMOUT': '86400',
10:35:09 INFO - 'USER': 'cltbld',
10:35:09 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:09 INFO - '_': '/tools/buildbot/bin/python'}
10:35:09 INFO - Ignoring indexes: https://pypi.python.org/simple
10:35:09 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
10:35:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
10:35:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
10:35:10 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))
10:35:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
10:35:10 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))
10:35:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
10:35:10 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))
10:35:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
10:35:10 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))
10:35:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
10:35:10 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))
10:35:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
10:35:11 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))
10:35:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
10:35:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
10:35:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
10:35:11 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))
10:35:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
10:35:11 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))
10:35:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
10:35:11 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))
10:35:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
10:35:12 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))
10:35:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
10:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
10:35:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
10:35:12 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))
10:35:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
10:35:12 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))
10:35:12 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
10:35:12 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))
10:35:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
10:35:13 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))
10:35:13 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
10:35:14 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
10:35:14 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))
10:35:14 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))
10:35:14 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))
10:35:14 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))
10:35:14 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))
10:35:14 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
10:35:14 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
10:35:15 INFO - Installing collected packages: six, blessings
10:35:15 INFO - Successfully installed blessings-1.6 six-1.10.0
10:35:15 INFO - Return code: 0
10:35:15 INFO - Done creating virtualenv /builds/slave/test/build/venv.
10:35:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
10:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
10:35:15 INFO - Reading from file tmpfile_stdout
10:35:15 INFO - Reading from file tmpfile_stderr
10:35:15 INFO - Current package versions:
10:35:15 INFO - blessings == 1.6
10:35:15 INFO - blobuploader == 1.2.4
10:35:15 INFO - docopt == 0.6.1
10:35:15 INFO - functools32 == 3.2.3.post2
10:35:15 INFO - jsonschema == 2.5.1
10:35:15 INFO - manifestparser == 1.2
10:35:16 INFO - mozInstall == 1.14
10:35:16 INFO - mozcrash == 1.0
10:35:16 INFO - mozdebug == 0.1
10:35:16 INFO - mozdevice == 0.51
10:35:16 INFO - mozfile == 1.2
10:35:16 INFO - mozhttpd == 0.7
10:35:16 INFO - mozinfo == 0.10
10:35:16 INFO - mozleak == 0.1
10:35:16 INFO - mozlog == 3.5
10:35:16 INFO - moznetwork == 0.27
10:35:16 INFO - mozprocess == 0.25
10:35:16 INFO - mozprofile == 0.29
10:35:16 INFO - mozrunner == 6.13
10:35:16 INFO - mozscreenshot == 0.1
10:35:16 INFO - mozsystemmonitor == 0.3
10:35:16 INFO - moztest == 0.8
10:35:16 INFO - mozversion == 1.4
10:35:16 INFO - psutil == 3.1.1
10:35:16 INFO - requests == 1.2.3
10:35:16 INFO - six == 1.10.0
10:35:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
10:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:35:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:35:16 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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:35:16 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
10:35:16 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
10:35:16 INFO - Using env: {'DISPLAY': ':0',
10:35:16 INFO - 'HOME': '/home/cltbld',
10:35:16 INFO - 'LANG': 'en_US.UTF-8',
10:35:16 INFO - 'LANGUAGE': 'en_US:en',
10:35:16 INFO - 'LOGNAME': 'cltbld',
10:35:16 INFO - 'MAIL': '/var/mail/cltbld',
10:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:16 INFO - 'MOZ_NO_REMOTE': '1',
10:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:16 INFO - 'NO_EM_RESTART': '1',
10:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:16 INFO - 'PWD': '/builds/slave/test',
10:35:16 INFO - 'SHELL': '/bin/bash',
10:35:16 INFO - 'SHLVL': '1',
10:35:16 INFO - 'TERM': 'linux',
10:35:16 INFO - 'TMOUT': '86400',
10:35:16 INFO - 'USER': 'cltbld',
10:35:16 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:16 INFO - '_': '/tools/buildbot/bin/python'}
10:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple
10:35:16 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))
10:35:16 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))
10:35:16 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))
10:35:16 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))
10:35:16 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))
10:35:16 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))
10:35:16 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))
10:35:16 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))
10:35:16 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))
10:35:16 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
10:35:17 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:18 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
10:35:18 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))
10:35:18 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))
10:35:18 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
10:35:18 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:19 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
10:35:19 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:20 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
10:35:20 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:21 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
10:35:21 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:23 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
10:35:23 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
10:35:23 INFO - Found existing installation: requests 1.2.3
10:35:23 INFO - Uninstalling requests-1.2.3:
10:35:23 INFO - Successfully uninstalled requests-1.2.3
10:35:24 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
10:35:24 INFO - Return code: 0
10:35:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
10:35:24 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')]}
10:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:35:24 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')]}
10:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:35:24 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 0xe202d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xff8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x119b930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, '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': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882', '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
10:35:24 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
10:35:24 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
10:35:24 INFO - Using env: {'DISPLAY': ':0',
10:35:24 INFO - 'HOME': '/home/cltbld',
10:35:24 INFO - 'LANG': 'en_US.UTF-8',
10:35:24 INFO - 'LANGUAGE': 'en_US:en',
10:35:24 INFO - 'LOGNAME': 'cltbld',
10:35:24 INFO - 'MAIL': '/var/mail/cltbld',
10:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:24 INFO - 'MOZ_NO_REMOTE': '1',
10:35:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:24 INFO - 'NO_EM_RESTART': '1',
10:35:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:24 INFO - 'PWD': '/builds/slave/test',
10:35:24 INFO - 'SHELL': '/bin/bash',
10:35:24 INFO - 'SHLVL': '1',
10:35:24 INFO - 'TERM': 'linux',
10:35:24 INFO - 'TMOUT': '86400',
10:35:24 INFO - 'USER': 'cltbld',
10:35:24 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:24 INFO - '_': '/tools/buildbot/bin/python'}
10:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple
10:35:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
10:35:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
10:35:24 INFO - Return code: 0
10:35:24 INFO - Running post-action listener: _resource_record_post_action
10:35:24 INFO - Running post-action listener: _start_resource_monitoring
10:35:24 INFO - Starting resource monitoring.
10:35:24 INFO - [mozharness: 2017-11-10 18:35:24.661293Z] Finished create-virtualenv step (success)
10:35:24 INFO - [mozharness: 2017-11-10 18:35:24.661759Z] Running install step.
10:35:24 INFO - Running pre-action listener: _resource_record_pre_action
10:35:24 INFO - Running main action method: install
10:35:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
10:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
10:35:25 INFO - Reading from file tmpfile_stdout
10:35:25 INFO - Reading from file tmpfile_stderr
10:35:25 INFO - Detecting whether we're running mozinstall >=1.0...
10:35:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
10:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
10:35:25 INFO - Reading from file tmpfile_stdout
10:35:25 INFO - Output received:
10:35:25 INFO - Usage: mozinstall [options] installer
10:35:25 INFO - Options:
10:35:25 INFO - -h, --help show this help message and exit
10:35:25 INFO - -d DEST, --destination=DEST
10:35:25 INFO - Directory to install application into. [default:
10:35:25 INFO - "/builds/slave/test"]
10:35:25 INFO - --app=APP Application being installed. [default: firefox]
10:35:25 INFO - mkdir: /builds/slave/test/build/application
10:35:25 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']
10:35:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
10:35:43 INFO - Reading from file tmpfile_stdout
10:35:43 INFO - Output received:
10:35:43 INFO - /builds/slave/test/build/application/firefox/firefox
10:35:43 INFO - Running post-action listener: _resource_record_post_action
10:35:43 INFO - [mozharness: 2017-11-10 18:35:43.594851Z] Finished install step (success)
10:35:43 INFO - [mozharness: 2017-11-10 18:35:43.594994Z] Running setup-mitmproxy step.
10:35:43 INFO - Running pre-action listener: _resource_record_pre_action
10:35:43 INFO - Running main action method: setup_mitmproxy
10:35:43 INFO - Skipping: mitmproxy is not required
10:35:43 INFO - Running post-action listener: _resource_record_post_action
10:35:43 INFO - [mozharness: 2017-11-10 18:35:43.595681Z] Finished setup-mitmproxy step (success)
10:35:43 INFO - [mozharness: 2017-11-10 18:35:43.595812Z] Running run-tests step.
10:35:43 INFO - Running pre-action listener: _resource_record_pre_action
10:35:43 INFO - Running pre-action listener: _set_gcov_prefix
10:35:43 INFO - Running main action method: run_tests
10:35:43 WARNING - Try message not found.
10:35:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
10:35:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
10:35:43 INFO - Python 2.7.3
10:35:43 INFO - Return code: 0
10:35:43 INFO - grabbing minidump binary from tooltool
10:35:43 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')]}
10:35:43 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 0x1198ad0>, '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 0x11999d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1199e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
10:35:43 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
10:35:43 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
10:35:43 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
10:35:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
10:35:43 INFO - Return code: 0
10:35:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
10:35:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
10:35:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
10:35:43 INFO - ENV: RUST_BACKTRACE is now full
10:35:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
10:35:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
10:35:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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
10:35:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-032 --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
10:35:43 INFO - Using env: {'DISPLAY': ':0',
10:35:43 INFO - 'HOME': '/home/cltbld',
10:35:43 INFO - 'LANG': 'en_US.UTF-8',
10:35:43 INFO - 'LANGUAGE': 'en_US:en',
10:35:43 INFO - 'LOGNAME': 'cltbld',
10:35:43 INFO - 'MAIL': '/var/mail/cltbld',
10:35:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:35:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:35:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:43 INFO - 'MOZ_NO_REMOTE': '1',
10:35:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:35:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:43 INFO - 'NO_EM_RESTART': '1',
10:35:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:43 INFO - 'PWD': '/builds/slave/test',
10:35:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:35:43 INFO - 'RUST_BACKTRACE': 'full',
10:35:43 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:35:43 INFO - 'SHELL': '/bin/bash',
10:35:43 INFO - 'SHLVL': '1',
10:35:43 INFO - 'TERM': 'linux',
10:35:43 INFO - 'TMOUT': '86400',
10:35:43 INFO - 'USER': 'cltbld',
10:35:43 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:35:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:43 INFO - '_': '/tools/buildbot/bin/python'}
10:35:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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
10:35:44 INFO - mozversion application_buildid: 20171110173245
10:35:44 INFO - mozversion application_changeset: b0ded9bbd517b1cef61beef4245323d1441fedc3
10:35:44 INFO - mozversion application_display_name: Nightly
10:35:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
10:35:44 INFO - mozversion application_name: Firefox
10:35:44 INFO - mozversion application_remotingname: firefox
10:35:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
10:35:44 INFO - mozversion application_vendor: Mozilla
10:35:44 INFO - mozversion application_version: 58.0a1
10:35:44 INFO - mozversion platform_buildid: 20171110173245
10:35:44 INFO - mozversion platform_changeset: b0ded9bbd517b1cef61beef4245323d1441fedc3
10:35:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
10:35:44 INFO - mozversion platform_version: 58.0a1
10:35:44 INFO - using testdate: 1510338944
10:35:44 INFO - actual date: 1510338944
10:35:44 INFO - starting webserver on 'localhost:54189'
10:35:44 INFO - SUITE-START | Running 3 tests
10:35:44 INFO - TEST-START | basic_compositor_video
10:35:44 INFO - Initialising browser for basic_compositor_video test...
10:35:44 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54189/getInfo.html -profile /tmp/tmpwR6Ash/profile
10:35:44 INFO - TEST-INFO | started process 14492 (/builds/slave/test/build/application/firefox/firefox http://localhost:54189/getInfo.html)
10:35:49 INFO - TEST-INFO | 14492: exit 0
10:35:49 INFO - Browser initialized.
10:35:49 INFO - Running cycle 1/1 for basic_compositor_video test...
10:35:49 INFO - Using env: {'DISPLAY': ':0',
10:35:49 INFO - 'HOME': '/home/cltbld',
10:35:49 INFO - 'JSGC_DISABLE_POISONING': '1',
10:35:49 INFO - 'LANG': 'en_US.UTF-8',
10:35:49 INFO - 'LANGUAGE': 'en_US:en',
10:35:49 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
10:35:49 INFO - 'LOGNAME': 'cltbld',
10:35:49 INFO - 'MAIL': '/var/mail/cltbld',
10:35:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:35:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:35:49 INFO - 'MOZ_CRASHREPORTER': '1',
10:35:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:49 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
10:35:49 INFO - 'MOZ_NO_REMOTE': '1',
10:35:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:35:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:49 INFO - 'NO_EM_RESTART': '1',
10:35:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:49 INFO - 'PWD': '/builds/slave/test',
10:35:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:35:49 INFO - 'RUST_BACKTRACE': 'full',
10:35:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:35:49 INFO - 'SHELL': '/bin/bash',
10:35:49 INFO - 'SHLVL': '1',
10:35:49 INFO - 'TERM': 'linux',
10:35:49 INFO - 'TMOUT': '86400',
10:35:49 INFO - 'USER': 'cltbld',
10:35:49 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:35:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:49 INFO - '_': '/tools/buildbot/bin/python'}
10:35:49 INFO - TEST-INFO | started process 14761 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwR6Ash/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
10:35:50 INFO - PID 14761 |
10:35:50 INFO - PID 14761 | (/builds/slave/test/build/application/firefox/firefox:14812): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
10:35:50 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6883483398987058 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.671157059314954 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.742177700348432 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7168369098712453 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.060899864682002 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.832490421455941 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.534346289752648 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.378589527027027 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619653558052429 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.089770992366419 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.709417475728152 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.435731132075471 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.407870722433476 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930628415300548 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.553514644351438 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.127181818181821 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.154869888475822 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.994065934065924 ms/frame
10:36:36 INFO - PID 14761 |
10:36:36 INFO - PID 14761 | Cycle 1(1): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:37:22 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6828911946158158 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6690158465387828 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7578851786760399 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7155274442538588 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.081965986394559 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.123752577319588 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.5764183551847455 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5090350877192944 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6948481973434495 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635464788732395 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 10.276027397260268 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.712839805825249 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.410741444866911 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.698128342246005 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605798319327732 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.12327272727275 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.675836575875467 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11472222222223 ms/frame
10:37:22 INFO - PID 14761 |
10:37:22 INFO - PID 14761 | Cycle 1(2): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:38:08 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6837373737373738 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.672821906354515 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7287211981566817 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.754719298245615 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.167472222222222 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.048785425101215 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.5507692307692302 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4791565217391294 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.816017441860462 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.540664819944599 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.555111464968153 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.70966019417475 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.54096153846153 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81121621621624 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.452385892116189 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052469879518068 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.675428015564199 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052099447513815 ms/frame
10:38:08 INFO - PID 14761 |
10:38:08 INFO - PID 14761 | Cycle 1(3): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:38:54 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.68924268018018 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6725125418060198 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7732505910165486 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7531069237510957 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9842031872509955 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9147064579256377 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.5854599761051404 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5229577464788684 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.694354838709676 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.684758522727273 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.774999999999997 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 10.003050000000004 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.323886792452823 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.81297297297298 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.400371900826444 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.052379518072287 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.496992337164766 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17871508379887 ms/frame
10:38:54 INFO - PID 14761 |
10:38:54 INFO - PID 14761 | Cycle 1(4): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:39:41 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6818693946188341 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6728762541806017 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7344653179190752 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7228294573643403 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.225654929577465 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1594698544698545 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.615771084337352 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.559021352313164 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.597649253731343 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.512506887052344 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 10.069614093959741 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.349462616822429 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.23617977528089 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.870923913043478 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346728395061747 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.766411764705888 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.58482625482626 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.872364130434779 ms/frame
10:39:41 INFO - PID 14761 |
10:39:41 INFO - PID 14761 | Cycle 1(5): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:40:27 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6784955257270695 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670916666666669 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7508313885647608 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.724620689655172 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.173956884561893 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.221402953586497 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.5632007125890737 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.558798932384345 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.514770220588235 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572465181058495 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.4970253164557 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.347126168224298 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.282951127819548 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.934808743169397 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.19823170731707 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.908779761904778 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630038759689933 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.11372222222223 ms/frame
10:40:27 INFO - PID 14761 |
10:40:27 INFO - PID 14761 | Cycle 1(6): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:41:13 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6864783586284429 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.670267111853089 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7496384839650145 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7364366319444438 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.202226890756302 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.945700197238661 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.6025990396158445 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5087894736842142 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672807183364841 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620702247191015 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.26152777777778 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.392957746478876 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.538846153846148 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.935355191256821 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.60800420168068 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83994082840235 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.1958208955224 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.874972826086932 ms/frame
10:41:13 INFO - PID 14761 |
10:41:13 INFO - PID 14761 | Cycle 1(7): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:41:59 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6846574957888827 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6701961602671118 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.743259732713539 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7215103270223748 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115727023319615 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9298330058939093 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.473385416666668 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.373532883642491 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.629803001876175 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.54072022160665 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.869967105263163 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758487804878056 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.40849809885932 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.993076923076911 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.552928870292893 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.56196531791904 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451717557251904 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.240505617977556 ms/frame
10:41:59 INFO - PID 14761 |
10:41:59 INFO - PID 14761 | Cycle 1(8): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:42:45 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6839450056116723 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6719465329991652 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.753550555230859 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7408572671888605 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.065657181571815 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.057545638945235 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.67217258261934 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6106407942238237 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7930598455598465 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.602829131652669 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 10.275993150684933 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.305000000000003 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.538980769230763 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.9923901098901 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.049859437751007 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.909553571428573 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.494425287356343 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.813540540540526 ms/frame
10:42:45 INFO - PID 14761 |
10:42:45 INFO - PID 14761 | Cycle 1(9): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:43:31 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6837794612794612 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686655546288578 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7352544823597456 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7116082121471334 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.196328671328671 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.09862704918033 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.598297362110311 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.591364452423704 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.714885714285712 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.63430985915493 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.587220447284354 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.526857142857141 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.539500000000002 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.240308988764053 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346563786008247 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.909136904761908 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.281503759398515 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932021857923484 ms/frame
10:43:31 INFO - PID 14761 |
10:43:31 INFO - PID 14761 | Cycle 1(10): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:44:18 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6830482333146382 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710985797827906 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.759065064478312 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7139845758354757 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.070997286295794 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.194874213836478 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.606808894230769 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.616681735985536 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.618885767790263 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.451035422343321 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 9.556162420382172 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.134680365296798 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.322150943396236 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.698074866310165 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.298196721311484 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.127303030303015 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.495038314176224 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992280219780227 ms/frame
10:44:18 INFO - PID 14761 |
10:44:18 INFO - PID 14761 | Cycle 1(11): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:45:04 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827089175546832 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6767560771165129 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7649647058823532 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7160034305317322 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_startup = 4.191298882681566 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1408592132505175 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_startup = 3.6026710684273704 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1_inclip = 3.624565217391308 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.792461389961388 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6982336182336235 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_startup = 10.27474315068494 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.480p.60fps.webm_scale_2_inclip = 9.71174757281553 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.280187969924794 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754704301075307 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.296639344262314 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.202103658536565 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.410361216730049 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.934535519125665 ms/frame
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | Cycle 1(12): loaded http://localhost:54189/tests/video/video_playback.html (next: http://localhost:54189/tests/video/video_playback.html)
10:45:04 INFO - PID 14761 | __start_tp_report
10:45:04 INFO - PID 14761 | _x_x_mozilla_page_load
10:45:04 INFO - PID 14761 | _x_x_mozilla_page_load_details
10:45:04 INFO - PID 14761 | |i|pagename|runs|
10:45:04 INFO - PID 14761 | |0;240p.120fps.mp4_scale_1_startup;1.6883483398987058;1.6828911946158158;1.6837373737373738;1.68924268018018;1.6818693946188341;1.6784955257270695;1.6864783586284429;1.6846574957888827;1.6839450056116723;1.6837794612794612;1.6830482333146382;1.6827089175546832
10:45:04 INFO - PID 14761 | |1;240p.120fps.mp4_scale_1_inclip;1.671157059314954;1.6690158465387828;1.672821906354515;1.6725125418060198;1.6728762541806017;1.6670916666666669;1.670267111853089;1.6701961602671118;1.6719465329991652;1.6686655546288578;1.6710985797827906;1.6767560771165129
10:45:04 INFO - PID 14761 | |2;240p.120fps.mp4_scale_1.1_startup;1.742177700348432;1.7578851786760399;1.7287211981566817;1.7732505910165486;1.7344653179190752;1.7508313885647608;1.7496384839650145;1.743259732713539;1.753550555230859;1.7352544823597456;1.759065064478312;1.7649647058823532
10:45:04 INFO - PID 14761 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7168369098712453;1.7155274442538588;1.754719298245615;1.7531069237510957;1.7228294573643403;1.724620689655172;1.7364366319444438;1.7215103270223748;1.7408572671888605;1.7116082121471334;1.7139845758354757;1.7160034305317322
10:45:04 INFO - PID 14761 | |4;240p.120fps.mp4_scale_2_startup;4.060899864682002;4.081965986394559;4.167472222222222;3.9842031872509955;4.225654929577465;4.173956884561893;4.202226890756302;4.115727023319615;4.065657181571815;4.196328671328671;4.070997286295794;4.191298882681566
10:45:04 INFO - PID 14761 | |5;240p.120fps.mp4_scale_2_inclip;3.832490421455941;4.123752577319588;4.048785425101215;3.9147064579256377;4.1594698544698545;4.221402953586497;3.945700197238661;3.9298330058939093;4.057545638945235;4.09862704918033;4.194874213836478;4.1408592132505175
10:45:04 INFO - PID 14761 | |6;480p.60fps.webm_scale_1_startup;3.534346289752648;3.5764183551847455;3.5507692307692302;3.5854599761051404;3.615771084337352;3.5632007125890737;3.6025990396158445;3.473385416666668;3.67217258261934;3.598297362110311;3.606808894230769;3.6026710684273704
10:45:04 INFO - PID 14761 | |7;480p.60fps.webm_scale_1_inclip;3.378589527027027;3.5090350877192944;3.4791565217391294;3.5229577464788684;3.559021352313164;3.558798932384345;3.5087894736842142;3.373532883642491;3.6106407942238237;3.591364452423704;3.616681735985536;3.624565217391308
10:45:04 INFO - PID 14761 | |8;480p.60fps.webm_scale_1.1_startup;5.619653558052429;5.6948481973434495;5.816017441860462;5.694354838709676;5.597649253731343;5.514770220588235;5.672807183364841;5.629803001876175;5.7930598455598465;5.714885714285712;5.618885767790263;5.792461389961388
10:45:04 INFO - PID 14761 | |9;480p.60fps.webm_scale_1.1_inclip;5.089770992366419;5.635464788732395;5.540664819944599;5.684758522727273;5.512506887052344;5.572465181058495;5.620702247191015;5.54072022160665;5.602829131652669;5.63430985915493;5.451035422343321;5.6982336182336235
10:45:04 INFO - PID 14761 | |10;480p.60fps.webm_scale_2_startup;9.709417475728152;10.276027397260268;9.555111464968153;9.774999999999997;10.069614093959741;9.4970253164557;9.26152777777778;9.869967105263163;10.275993150684933;9.587220447284354;9.556162420382172;10.27474315068494
10:45:04 INFO - PID 14761 | |11;480p.60fps.webm_scale_2_inclip;9.435731132075471;9.712839805825249;9.70966019417475;10.003050000000004;9.349462616822429;9.347126168224298;9.392957746478876;9.758487804878056;9.305000000000003;9.526857142857141;9.134680365296798;9.71174757281553
10:45:04 INFO - PID 14761 | |12;1080p.60fps.mp4_scale_1_startup;11.407870722433476;11.410741444866911;11.54096153846153;11.323886792452823;11.23617977528089;11.282951127819548;11.538846153846148;11.40849809885932;11.538980769230763;11.539500000000002;11.322150943396236;11.280187969924794
10:45:04 INFO - PID 14761 | |13;1080p.60fps.mp4_scale_1_inclip;10.930628415300548;10.698128342246005;10.81121621621624;10.81297297297298;10.870923913043478;10.934808743169397;10.935355191256821;10.993076923076911;10.9923901098901;11.240308988764053;10.698074866310165;10.754704301075307
10:45:04 INFO - PID 14761 | |14;1080p.60fps.mp4_scale_1.1_startup;12.553514644351438;12.605798319327732;12.452385892116189;12.400371900826444;12.346728395061747;12.19823170731707;12.60800420168068;12.552928870292893;12.049859437751007;12.346563786008247;12.298196721311484;12.296639344262314
10:45:04 INFO - PID 14761 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.127181818181821;12.12327272727275;12.052469879518068;12.052379518072287;11.766411764705888;11.908779761904778;11.83994082840235;11.56196531791904;11.909553571428573;11.909136904761908;12.127303030303015;12.202103658536565
10:45:04 INFO - PID 14761 | |16;1080p.60fps.mp4_scale_2_startup;11.154869888475822;11.675836575875467;11.675428015564199;11.496992337164766;11.58482625482626;11.630038759689933;11.1958208955224;11.451717557251904;11.494425287356343;11.281503759398515;11.495038314176224;11.410361216730049
10:45:04 INFO - PID 14761 | |17;1080p.60fps.mp4_scale_2_inclip;10.994065934065924;11.11472222222223;11.052099447513815;11.17871508379887;10.872364130434779;11.11372222222223;10.874972826086932;11.240505617977556;10.813540540540526;10.932021857923484;10.992280219780227;10.934535519125665
10:45:04 INFO - PID 14761 | __end_tp_report
10:45:04 INFO - PID 14761 | __start_cc_report
10:45:04 INFO - PID 14761 | _x_x_mozilla_cycle_collect,603
10:45:04 INFO - PID 14761 | __end_cc_report
10:45:04 INFO - PID 14761 | __startTimestamp1510339504486__endTimestamp
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | ------- Summary: start -------
10:45:04 INFO - PID 14761 | Number of tests: 18
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
10:45:04 INFO - PID 14761 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
10:45:04 INFO - PID 14761 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.75 Median:1.75 stddev:0.01 (0.8%) stddev-sans-first:0.01
10:45:04 INFO - PID 14761 | Values: 1.7 1.8 1.7 1.8 1.7 1.8 1.7 1.7 1.8 1.7 1.8 1.8
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.73 Median:1.72 stddev:0.02 (0.9%) stddev-sans-first:0.02
10:45:04 INFO - PID 14761 | Values: 1.7 1.7 1.8 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.13 Median:4.17 stddev:0.08 (1.8%) stddev-sans-first:0.08
10:45:04 INFO - PID 14761 | Values: 4.1 4.1 4.2 4.0 4.2 4.2 4.2 4.1 4.1 4.2 4.1 4.2
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.06 Median:4.11 stddev:0.12 (3.0%) stddev-sans-first:0.11
10:45:04 INFO - PID 14761 | Values: 3.8 4.1 4.0 3.9 4.2 4.2 3.9 3.9 4.1 4.1 4.2 4.1
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.58 Median:3.60 stddev:0.05 (1.4%) stddev-sans-first:0.05
10:45:04 INFO - PID 14761 | Values: 3.5 3.6 3.6 3.6 3.6 3.6 3.6 3.5 3.7 3.6 3.6 3.6
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.53 Median:3.56 stddev:0.08 (2.4%) stddev-sans-first:0.07
10:45:04 INFO - PID 14761 | Values: 3.4 3.5 3.5 3.5 3.6 3.6 3.5 3.4 3.6 3.6 3.6 3.6
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.68 Median:5.69 stddev:0.09 (1.6%) stddev-sans-first:0.09
10:45:04 INFO - PID 14761 | Values: 5.6 5.7 5.8 5.7 5.6 5.5 5.7 5.6 5.8 5.7 5.6 5.8
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.55 Median:5.61 stddev:0.16 (2.9%) stddev-sans-first:0.08
10:45:04 INFO - PID 14761 | Values: 5.1 5.6 5.5 5.7 5.5 5.6 5.6 5.5 5.6 5.6 5.5 5.7
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.81 Median:9.56 stddev:0.35 (3.6%) stddev-sans-first:0.36
10:45:04 INFO - PID 14761 | Values: 9.7 10.3 9.6 9.8 10.1 9.5 9.3 9.9 10.3 9.6 9.6 10.3
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.53 Median:9.48 stddev:0.25 (2.6%) stddev-sans-first:0.26
10:45:04 INFO - PID 14761 | Values: 9.4 9.7 9.7 10.0 9.3 9.3 9.4 9.8 9.3 9.5 9.1 9.7
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.40 Median:11.41 stddev:0.11 (1.0%) stddev-sans-first:0.12
10:45:04 INFO - PID 14761 | Values: 11.4 11.4 11.5 11.3 11.2 11.3 11.5 11.4 11.5 11.5 11.3 11.3
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.89 Median:10.93 stddev:0.15 (1.4%) stddev-sans-first:0.16
10:45:04 INFO - PID 14761 | Values: 10.9 10.7 10.8 10.8 10.9 10.9 10.9 11.0 11.0 11.2 10.7 10.8
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.39 Median:12.43 stddev:0.17 (1.4%) stddev-sans-first:0.17
10:45:04 INFO - PID 14761 | Values: 12.6 12.6 12.5 12.4 12.3 12.2 12.6 12.6 12.0 12.3 12.3 12.3
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.97 Median:12.05 stddev:0.18 (1.5%) stddev-sans-first:0.19
10:45:04 INFO - PID 14761 | Values: 12.1 12.1 12.1 12.1 11.8 11.9 11.8 11.6 11.9 11.9 12.1 12.2
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.46 Median:11.50 stddev:0.18 (1.5%) stddev-sans-first:0.15
10:45:04 INFO - PID 14761 | Values: 11.2 11.7 11.7 11.5 11.6 11.6 11.2 11.5 11.5 11.3 11.5 11.4
10:45:04 INFO - PID 14761 |
10:45:04 INFO - PID 14761 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.01 Median:11.02 stddev:0.13 (1.2%) stddev-sans-first:0.14
10:45:04 INFO - PID 14761 | Values: 11.0 11.1 11.1 11.2 10.9 11.1 10.9 11.2 10.8 10.9 11.0 10.9
10:45:04 INFO - PID 14761 | -------- Summary: end --------
10:45:04 INFO - PID 14761 |
10:45:05 INFO - TEST-INFO | 14761: exit 0
10:45:05 INFO - TEST-OK | basic_compositor_video | took 561346ms
10:45:05 INFO - TEST-START | glvideo
10:45:05 INFO - Initialising browser for glvideo test...
10:45:05 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54189/getInfo.html -profile /tmp/tmpEkwRRH/profile
10:45:05 INFO - TEST-INFO | started process 16009 (/builds/slave/test/build/application/firefox/firefox http://localhost:54189/getInfo.html)
10:45:10 INFO - TEST-INFO | 16009: exit 0
10:45:10 INFO - Browser initialized.
10:45:10 INFO - Running cycle 1/1 for glvideo test...
10:45:10 INFO - Using env: {'DISPLAY': ':0',
10:45:10 INFO - 'HOME': '/home/cltbld',
10:45:10 INFO - 'JSGC_DISABLE_POISONING': '1',
10:45:10 INFO - 'LANG': 'en_US.UTF-8',
10:45:10 INFO - 'LANGUAGE': 'en_US:en',
10:45:10 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
10:45:10 INFO - 'LOGNAME': 'cltbld',
10:45:10 INFO - 'MAIL': '/var/mail/cltbld',
10:45:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:45:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:45:10 INFO - 'MOZ_CRASHREPORTER': '1',
10:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:45:10 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
10:45:10 INFO - 'MOZ_NO_REMOTE': '1',
10:45:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:45:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:45:10 INFO - 'NO_EM_RESTART': '1',
10:45:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:45:10 INFO - 'PWD': '/builds/slave/test',
10:45:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:45:10 INFO - 'RUST_BACKTRACE': 'full',
10:45:10 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:45:10 INFO - 'SHELL': '/bin/bash',
10:45:10 INFO - 'SHLVL': '1',
10:45:10 INFO - 'TERM': 'linux',
10:45:10 INFO - 'TMOUT': '86400',
10:45:10 INFO - 'USER': 'cltbld',
10:45:10 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:45:10 INFO - '_': '/tools/buildbot/bin/python'}
10:45:10 INFO - TEST-INFO | started process 16284 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEkwRRH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
10:45:11 INFO - PID 16284 |
10:45:11 INFO - PID 16284 | (/builds/slave/test/build/application/firefox/firefox:16336): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
10:45:11 INFO - PID 16284 |
10:45:12 INFO - PID 16284 | [talos glvideo result] Mean tick time across 100 ticks: 6.825950000000001 ms
10:45:12 INFO - PID 16284 | Cycle 1(1): loaded http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html)
10:45:14 INFO - PID 16284 | [talos glvideo result] Mean tick time across 100 ticks: 6.510150000000001 ms
10:45:14 INFO - PID 16284 | Cycle 1(2): loaded http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html)
10:45:15 INFO - PID 16284 | [talos glvideo result] Mean tick time across 100 ticks: 6.57785 ms
10:45:15 INFO - PID 16284 | Cycle 1(3): loaded http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html)
10:45:16 INFO - PID 16284 | [talos glvideo result] Mean tick time across 100 ticks: 6.65275 ms
10:45:16 INFO - PID 16284 | Cycle 1(4): loaded http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html)
10:45:18 INFO - PID 16284 | [talos glvideo result] Mean tick time across 100 ticks: 6.585 ms
10:45:18 INFO - PID 16284 | Cycle 1(5): loaded http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:54189/tests/webgl/benchmarks/video/video_upload.html)
10:45:18 INFO - PID 16284 | __start_tp_report
10:45:18 INFO - PID 16284 | _x_x_mozilla_page_load
10:45:18 INFO - PID 16284 | _x_x_mozilla_page_load_details
10:45:18 INFO - PID 16284 | |i|pagename|runs|
10:45:18 INFO - PID 16284 | |0;Mean tick time across 100 ticks: ;6.825950000000001;6.510150000000001;6.57785;6.65275;6.585
10:45:18 INFO - PID 16284 | __end_tp_report
10:45:18 INFO - PID 16284 | __start_cc_report
10:45:18 INFO - PID 16284 | _x_x_mozilla_cycle_collect,270
10:45:18 INFO - PID 16284 | __end_cc_report
10:45:18 INFO - PID 16284 | __startTimestamp1510339518329__endTimestamp
10:45:18 INFO - PID 16284 |
10:45:18 INFO - PID 16284 | ------- Summary: start -------
10:45:18 INFO - PID 16284 | Number of tests: 1
10:45:18 INFO - PID 16284 |
10:45:18 INFO - PID 16284 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.63 Median:6.58 stddev:0.12 (1.8%) stddev-sans-first:0.06
10:45:18 INFO - PID 16284 | Values: 6.8 6.5 6.6 6.7 6.6
10:45:18 INFO - PID 16284 | -------- Summary: end --------
10:45:18 INFO - PID 16284 |
10:45:18 INFO - PID 16284 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
10:45:18 INFO - TEST-INFO | 16284: exit 0
10:45:18 INFO - TEST-OK | glvideo | took 13606ms
10:45:18 INFO - TEST-START | displaylist_mutate
10:45:18 INFO - Initialising browser for displaylist_mutate test...
10:45:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54189/getInfo.html -profile /tmp/tmpbZCCiB/profile
10:45:18 INFO - TEST-INFO | started process 16508 (/builds/slave/test/build/application/firefox/firefox http://localhost:54189/getInfo.html)
10:45:23 INFO - TEST-INFO | 16508: exit 0
10:45:23 INFO - Browser initialized.
10:45:23 INFO - Running cycle 1/1 for displaylist_mutate test...
10:45:23 INFO - Using env: {'DISPLAY': ':0',
10:45:23 INFO - 'HOME': '/home/cltbld',
10:45:23 INFO - 'JSGC_DISABLE_POISONING': '1',
10:45:23 INFO - 'LANG': 'en_US.UTF-8',
10:45:23 INFO - 'LANGUAGE': 'en_US:en',
10:45:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
10:45:23 INFO - 'LOGNAME': 'cltbld',
10:45:23 INFO - 'MAIL': '/var/mail/cltbld',
10:45:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:45:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:45:23 INFO - 'MOZ_CRASHREPORTER': '1',
10:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:45:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
10:45:23 INFO - 'MOZ_NO_REMOTE': '1',
10:45:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:45:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:45:23 INFO - 'NO_EM_RESTART': '1',
10:45:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:45:23 INFO - 'PWD': '/builds/slave/test',
10:45:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:45:23 INFO - 'RUST_BACKTRACE': 'full',
10:45:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:45:23 INFO - 'SHELL': '/bin/bash',
10:45:23 INFO - 'SHLVL': '1',
10:45:23 INFO - 'TERM': 'linux',
10:45:23 INFO - 'TMOUT': '86400',
10:45:23 INFO - 'USER': 'cltbld',
10:45:23 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1510338867.177146-1985830882',
10:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:45:23 INFO - '_': '/tools/buildbot/bin/python'}
10:45:23 INFO - TEST-INFO | started process 16778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbZCCiB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
10:45:24 INFO - PID 16778 |
10:45:24 INFO - PID 16778 | (/builds/slave/test/build/application/firefox/firefox:16829): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
10:45:24 INFO - PID 16778 |
10:45:45 INFO - PID 16778 | Cycle 1(1): loaded http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html)
10:46:06 INFO - PID 16778 | Cycle 1(2): loaded http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html)
10:46:27 INFO - PID 16778 | Cycle 1(3): loaded http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html)
10:46:47 INFO - PID 16778 | Cycle 1(4): loaded http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html)
10:47:08 INFO - PID 16778 | Cycle 1(5): loaded http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:54189/tests/layout/benchmarks/displaylist_mutate.html)
10:47:09 INFO - PID 16778 | __start_tp_report
10:47:09 INFO - PID 16778 | _x_x_mozilla_page_load
10:47:09 INFO - PID 16778 | _x_x_mozilla_page_load_details
10:47:09 INFO - PID 16778 | |i|pagename|runs|
10:47:09 INFO - PID 16778 | |0;/displaylist_mutate.html;19704.45;20192.885;20208.28;20127.82;20136.070000000003
10:47:09 INFO - PID 16778 | __end_tp_report
10:47:09 INFO - PID 16778 | __start_cc_report
10:47:09 INFO - PID 16778 | _x_x_mozilla_cycle_collect,199
10:47:09 INFO - PID 16778 | __end_cc_report
10:47:09 INFO - PID 16778 | __startTimestamp1510339629161__endTimestamp
10:47:09 INFO - PID 16778 |
10:47:09 INFO - PID 16778 | ------- Summary: start -------
10:47:09 INFO - PID 16778 | Number of tests: 1
10:47:09 INFO - PID 16778 |
10:47:09 INFO - PID 16778 | [#0] /displaylist_mutate.html Cycles:5 Average:20073.90 Median:20136.07 stddev:209.45 (1.0%) stddev-sans-first:40.26
10:47:09 INFO - PID 16778 | Values: 19704.5 20192.9 20208.3 20127.8 20136.1
10:47:09 INFO - PID 16778 | -------- Summary: end --------
10:47:09 INFO - PID 16778 |
10:47:09 INFO - TEST-INFO | 16778: exit 0
10:47:09 INFO - TEST-OK | displaylist_mutate | took 110913ms
10:47:09 INFO - SUITE-END | took 685s
10:47:10 INFO - Completed test suite (00:11:26)
10:47:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.600495047604921, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6883483398987058, 1.6828911946158158, 1.6837373737373738, 1.68924268018018, 1.6818693946188341, 1.6784955257270695, 1.6864783586284429, 1.6846574957888827, 1.6839450056116723, 1.6837794612794612, 1.6830482333146382, 1.6827089175546832], "value": 1.6837373737373738, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.671157059314954, 1.6690158465387828, 1.672821906354515, 1.6725125418060198, 1.6728762541806017, 1.6670916666666669, 1.670267111853089, 1.6701961602671118, 1.6719465329991652, 1.6686655546288578, 1.6710985797827906, 1.6767560771165129], "value": 1.6710985797827906, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.742177700348432, 1.7578851786760399, 1.7287211981566817, 1.7732505910165486, 1.7344653179190752, 1.7508313885647608, 1.7496384839650145, 1.743259732713539, 1.753550555230859, 1.7352544823597456, 1.759065064478312, 1.7649647058823532], "value": 1.7508313885647608, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7168369098712453, 1.7155274442538588, 1.754719298245615, 1.7531069237510957, 1.7228294573643403, 1.724620689655172, 1.7364366319444438, 1.7215103270223748, 1.7408572671888605, 1.7116082121471334, 1.7139845758354757, 1.7160034305317322], "value": 1.7228294573643403, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.060899864682002, 4.081965986394559, 4.167472222222222, 3.9842031872509955, 4.225654929577465, 4.173956884561893, 4.202226890756302, 4.115727023319615, 4.065657181571815, 4.196328671328671, 4.070997286295794, 4.191298882681566], "value": 4.167472222222222, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.832490421455941, 4.123752577319588, 4.048785425101215, 3.9147064579256377, 4.1594698544698545, 4.221402953586497, 3.945700197238661, 3.9298330058939093, 4.057545638945235, 4.09862704918033, 4.194874213836478, 4.1408592132505175], "value": 4.09862704918033, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.534346289752648, 3.5764183551847455, 3.5507692307692302, 3.5854599761051404, 3.615771084337352, 3.5632007125890737, 3.6025990396158445, 3.473385416666668, 3.67217258261934, 3.598297362110311, 3.606808894230769, 3.6026710684273704], "value": 3.598297362110311, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.378589527027027, 3.5090350877192944, 3.4791565217391294, 3.5229577464788684, 3.559021352313164, 3.558798932384345, 3.5087894736842142, 3.373532883642491, 3.6106407942238237, 3.591364452423704, 3.616681735985536, 3.624565217391308], "value": 3.558798932384345, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.619653558052429, 5.6948481973434495, 5.816017441860462, 5.694354838709676, 5.597649253731343, 5.514770220588235, 5.672807183364841, 5.629803001876175, 5.7930598455598465, 5.714885714285712, 5.618885767790263, 5.792461389961388], "value": 5.694354838709676, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.089770992366419, 5.635464788732395, 5.540664819944599, 5.684758522727273, 5.512506887052344, 5.572465181058495, 5.620702247191015, 5.54072022160665, 5.602829131652669, 5.63430985915493, 5.451035422343321, 5.6982336182336235], "value": 5.602829131652669, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.709417475728152, 10.276027397260268, 9.555111464968153, 9.774999999999997, 10.069614093959741, 9.4970253164557, 9.26152777777778, 9.869967105263163, 10.275993150684933, 9.587220447284354, 9.556162420382172, 10.27474315068494], "value": 9.774999999999997, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.435731132075471, 9.712839805825249, 9.70966019417475, 10.003050000000004, 9.349462616822429, 9.347126168224298, 9.392957746478876, 9.758487804878056, 9.305000000000003, 9.526857142857141, 9.134680365296798, 9.71174757281553], "value": 9.526857142857141, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.407870722433476, 11.410741444866911, 11.54096153846153, 11.323886792452823, 11.23617977528089, 11.282951127819548, 11.538846153846148, 11.40849809885932, 11.538980769230763, 11.539500000000002, 11.322150943396236, 11.280187969924794], "value": 11.40849809885932, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.930628415300548, 10.698128342246005, 10.81121621621624, 10.81297297297298, 10.870923913043478, 10.934808743169397, 10.935355191256821, 10.993076923076911, 10.9923901098901, 11.240308988764053, 10.698074866310165, 10.754704301075307], "value": 10.870923913043478, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.553514644351438, 12.605798319327732, 12.452385892116189, 12.400371900826444, 12.346728395061747, 12.19823170731707, 12.60800420168068, 12.552928870292893, 12.049859437751007, 12.346563786008247, 12.298196721311484, 12.296639344262314], "value": 12.346728395061747, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.127181818181821, 12.12327272727275, 12.052469879518068, 12.052379518072287, 11.766411764705888, 11.908779761904778, 11.83994082840235, 11.56196531791904, 11.909553571428573, 11.909136904761908, 12.127303030303015, 12.202103658536565], "value": 11.909553571428573, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.154869888475822, 11.675836575875467, 11.675428015564199, 11.496992337164766, 11.58482625482626, 11.630038759689933, 11.1958208955224, 11.451717557251904, 11.494425287356343, 11.281503759398515, 11.495038314176224, 11.410361216730049], "value": 11.495038314176224, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.994065934065924, 11.11472222222223, 11.052099447513815, 11.17871508379887, 10.872364130434779, 11.11372222222223, 10.874972826086932, 11.240505617977556, 10.813540540540526, 10.932021857923484, 10.992280219780227, 10.934535519125665], "value": 10.992280219780227, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.825950000000001, 6.510150000000001, 6.57785, 6.65275, 6.585], "value": 6.581424999999999, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [19704.45, 20192.885, 20208.28, 20127.82, 20136.070000000003], "value": 20164.4775, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
10:47:10 INFO - Return code: 0
10:47:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
10:47:10 INFO - # TBPL SUCCESS #
10:47:10 INFO - Running post-action listener: _package_coverage_data
10:47:10 INFO - Running post-action listener: _resource_record_post_action
10:47:10 INFO - [mozharness: 2017-11-10 18:47:10.243596Z] Finished run-tests step (success)
10:47:10 INFO - Running post-run listener: _resource_record_post_run
10:47:10 INFO - Total resource usage - Wall time: 705s; CPU: 35.0%; Read bytes: 16384; Write bytes: 344014848; Read time: 300; Write time: 273736
10:47:10 INFO - TinderboxPrint: CPU usage
35.0%
10:47:10 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 300
10:47:10 INFO - TinderboxPrint: I/O write bytes / time
344,014,848 / 273,736
10:47:10 INFO - TinderboxPrint: CPU idle
3,604.2 (65.0%)
10:47:10 INFO - TinderboxPrint: CPU system
316.9 (5.7%)
10:47:10 INFO - TinderboxPrint: CPU user
1,605.3 (29.0%)
10:47:10 INFO - TinderboxPrint: Swap in / out
0 / 0
10:47:10 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 6418432; Read time: 0; Write time: 1132
10:47:10 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
10:47:10 INFO - run-tests - Wall time: 687s; CPU: 36.0%; Read bytes: 16384; Write bytes: 337072128; Read time: 300; Write time: 272604
10:47:10 INFO - Running post-run listener: _upload_blobber_files
10:47:10 INFO - Blob upload gear active.
10:47:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
10:47:10 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
10:47:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
10:47:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
10:47:10 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:10 INFO - SNIMissingWarning
10:47:10 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:10 INFO - InsecurePlatformWarning
10:47:10 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:47:10 INFO - SubjectAltNameWarning
10:47:11 INFO - (blobuploader) - INFO - Open directory for files ...
10:47:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
10:47:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:47:11 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:47:11 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:11 INFO - InsecurePlatformWarning
10:47:11 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:47:11 INFO - SubjectAltNameWarning
10:47:11 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:11 INFO - InsecurePlatformWarning
10:47:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
10:47:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:47:11 INFO - (blobuploader) - INFO - Done attempting.
10:47:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
10:47:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:47:11 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:47:12 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:12 INFO - InsecurePlatformWarning
10:47:12 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:47:12 INFO - SubjectAltNameWarning
10:47:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:16 INFO - InsecurePlatformWarning
10:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
10:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:47:17 INFO - (blobuploader) - INFO - Done attempting.
10:47:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
10:47:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:47:17 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:47:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:17 INFO - InsecurePlatformWarning
10:47:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:47:17 INFO - SubjectAltNameWarning
10:47:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:17 INFO - InsecurePlatformWarning
10:47:18 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
10:47:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:47:18 INFO - (blobuploader) - INFO - Done attempting.
10:47:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
10:47:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:47:18 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:47:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:18 INFO - InsecurePlatformWarning
10:47:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:47:18 INFO - SubjectAltNameWarning
10:47:18 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:47:18 INFO - InsecurePlatformWarning
10:47:18 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
10:47:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:47:18 INFO - (blobuploader) - INFO - Done attempting.
10:47:18 INFO - (blobuploader) - INFO - Iteration through files over.
10:47:18 INFO - Return code: 0
10:47:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
10:47:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
10:47:18 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34136705ace13cc979c26be844b1ee6c1b8e841a71f820356bcc5acbcced33720173577365cddc4abc68e2e0cc47e0868ff92a6ee45bacfdb5a5f3235feb9017", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa0f4964aa492650be22f762d9592bcffd6922870c9cd52fb79b4894da6be95c2bd6ab5b743f86ca43d8366887f5cbd7ef5ba78d3fda5e4ab25754af6dc428fd", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb0d57d39320c5312413329360c4bbaeda99c1d60fd768d3d6b3bd9b94e0a5538f8824ea398bdb5740af14aa00797a620393884731fd7e0542c9d8af641b975d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc7f2a883b4406529233f71a537f7e7bd88d24fede01da9e8dfa5796b8c211ffc7bd64345f580910ce43f52a2e0db556228782cd5c8644dccb7c8cef5119b15c"}
10:47:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
10:47:18 INFO - Writing to file /builds/slave/test/properties/blobber_files
10:47:18 INFO - Contents:
10:47:18 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34136705ace13cc979c26be844b1ee6c1b8e841a71f820356bcc5acbcced33720173577365cddc4abc68e2e0cc47e0868ff92a6ee45bacfdb5a5f3235feb9017", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa0f4964aa492650be22f762d9592bcffd6922870c9cd52fb79b4894da6be95c2bd6ab5b743f86ca43d8366887f5cbd7ef5ba78d3fda5e4ab25754af6dc428fd", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb0d57d39320c5312413329360c4bbaeda99c1d60fd768d3d6b3bd9b94e0a5538f8824ea398bdb5740af14aa00797a620393884731fd7e0542c9d8af641b975d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc7f2a883b4406529233f71a537f7e7bd88d24fede01da9e8dfa5796b8c211ffc7bd64345f580910ce43f52a2e0db556228782cd5c8644dccb7c8cef5119b15c"}
10:47:18 INFO - Running post-run listener: copy_logs_to_upload_dir
10:47:18 INFO - Copying logs to upload dir...
10:47:18 INFO - mkdir: /builds/slave/test/build/upload/logs
10:47:18 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=769.305080
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 49 secs) (at 2017-11-10 10:47:18.923461) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-10 10:47:18.926736) =========
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=745a4373a2a16df560f216e000000264-1510338867.177146-1985830882
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34136705ace13cc979c26be844b1ee6c1b8e841a71f820356bcc5acbcced33720173577365cddc4abc68e2e0cc47e0868ff92a6ee45bacfdb5a5f3235feb9017", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa0f4964aa492650be22f762d9592bcffd6922870c9cd52fb79b4894da6be95c2bd6ab5b743f86ca43d8366887f5cbd7ef5ba78d3fda5e4ab25754af6dc428fd", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb0d57d39320c5312413329360c4bbaeda99c1d60fd768d3d6b3bd9b94e0a5538f8824ea398bdb5740af14aa00797a620393884731fd7e0542c9d8af641b975d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc7f2a883b4406529233f71a537f7e7bd88d24fede01da9e8dfa5796b8c211ffc7bd64345f580910ce43f52a2e0db556228782cd5c8644dccb7c8cef5119b15c"}
build_url:https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011532
build_url: 'https://queue.taskcluster.net/v1/task/TSETt-vlSba2ChYGtbVCow/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/34136705ace13cc979c26be844b1ee6c1b8e841a71f820356bcc5acbcced33720173577365cddc4abc68e2e0cc47e0868ff92a6ee45bacfdb5a5f3235feb9017", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aa0f4964aa492650be22f762d9592bcffd6922870c9cd52fb79b4894da6be95c2bd6ab5b743f86ca43d8366887f5cbd7ef5ba78d3fda5e4ab25754af6dc428fd", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bb0d57d39320c5312413329360c4bbaeda99c1d60fd768d3d6b3bd9b94e0a5538f8824ea398bdb5740af14aa00797a620393884731fd7e0542c9d8af641b975d", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bc7f2a883b4406529233f71a537f7e7bd88d24fede01da9e8dfa5796b8c211ffc7bd64345f580910ce43f52a2e0db556228782cd5c8644dccb7c8cef5119b15c"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-10 10:47:18.956548) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 10:47:18.956868) =========
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=745a4373a2a16df560f216e000000264-1510338867.177146-1985830882
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004581
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-10 10:47:19.042474) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-10 10:47:19.042795) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-10 10:47:19.043124) =========
========= Total master_lag: 0.20 =========