builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-056
starttime: 1511865814.06
results: success (0)
revision: cff54d05bcad74654249e2a2d4735b491bc452fd
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.064173) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.064596) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.064909) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.085339) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.085656) =========
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=52faae308a556c440401f8e5000001e8-1511865670.455565-785011975
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-28 02:43:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 229M=0s
2017-11-28 02:43:34 (229 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.182752
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.286910) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.287282) =========
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=52faae308a556c440401f8e5000001e8-1511865670.455565-785011975
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.041480
========= master_lag: 0.02 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.347259) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:34.347647) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cff54d05bcad74654249e2a2d4735b491bc452fd --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 cff54d05bcad74654249e2a2d4735b491bc452fd --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=52faae308a556c440401f8e5000001e8-1511865670.455565-785011975
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-28 02:43:34,403 truncating revision to first 12 chars
2017-11-28 02:43:34,403 Setting DEBUG logging.
2017-11-28 02:43:34,403 attempt 1/10
2017-11-28 02:43:34,403 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cff54d05bcad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-28 02:43:34,788 unpacking tar archive at: mozilla-central-cff54d05bcad/testing/mozharness/
program finished with exit code 0
elapsedTime=0.980297
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:35.346763) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:35.347107) =========
script_repo_revision: cff54d05bcad74654249e2a2d4735b491bc452fd
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:35.347526) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:35.347836) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-28 02:43:35.363120) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 20 secs) (at 2017-11-28 02:43:35.363495) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=52faae308a556c440401f8e5000001e8-1511865670.455565-785011975
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
02:43:35 INFO - MultiFileLogger online at 20171128 02:43:35 in /builds/slave/test
02:43:35 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
02:43:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
02:43:35 INFO - {'append_to_log': False,
02:43:35 INFO - 'base_work_dir': '/builds/slave/test',
02:43:35 INFO - 'blob_upload_branch': 'Firefox',
02:43:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
02:43:35 INFO - 'branch': 'Firefox',
02:43:35 INFO - 'buildbot_json_path': 'buildprops.json',
02:43:35 INFO - 'code_coverage': False,
02:43:35 INFO - 'config_files': ('talos/linux_config.py',),
02:43:35 INFO - 'default_actions': ('clobber',
02:43:35 INFO - 'read-buildbot-config',
02:43:35 INFO - 'download-and-extract',
02:43:35 INFO - 'populate-webroot',
02:43:35 INFO - 'create-virtualenv',
02:43:35 INFO - 'install',
02:43:35 INFO - 'setup-mitmproxy',
02:43:35 INFO - 'run-tests'),
02:43:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:43:35 INFO - 'disable_ccov_upload': False,
02:43:35 INFO - 'disable_stylo': False,
02:43:35 INFO - 'download_minidump_stackwalk': True,
02:43:35 INFO - 'download_symbols': 'ondemand',
02:43:35 INFO - 'e10s': False,
02:43:35 INFO - 'enable_stylo': False,
02:43:35 INFO - 'enable_webrender': False,
02:43:35 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
02:43:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:43:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:43:35 INFO - 'gecko_profile': False,
02:43:35 INFO - 'gecko_profile_interval': 0,
02:43:35 INFO - 'installer_path': 'installer.exe',
02:43:35 INFO - 'jsd_code_coverage': False,
02:43:35 INFO - 'log_level': 'info',
02:43:35 INFO - 'log_name': 'talos',
02:43:35 INFO - 'log_to_console': True,
02:43:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
02:43:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
02:43:35 INFO - 'opt_config_files': (),
02:43:35 INFO - 'pip_index': False,
02:43:35 INFO - 'suite': 'g4-e10s',
02:43:35 INFO - 'system_bits': '32',
02:43:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:43:35 INFO - 'title': 'talos-linux64-ix-056',
02:43:35 INFO - 'tooltool_cache': '/builds/tooltool_cache',
02:43:35 INFO - 'use_talos_json': True,
02:43:35 INFO - 'verify': 'False',
02:43:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
02:43:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:43:35 INFO - 'work_dir': 'build'}
02:43:35 INFO - [mozharness: 2017-11-28 10:43:35.622080Z] Running clobber step.
02:43:35 INFO - Running pre-action listener: _resource_record_pre_action
02:43:35 INFO - Running main action method: clobber
02:43:35 INFO - rmtree: /builds/slave/test/build
02:43:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
02:43:36 INFO - Running post-action listener: _resource_record_post_action
02:43:36 INFO - [mozharness: 2017-11-28 10:43:36.655891Z] Finished clobber step (success)
02:43:36 INFO - [mozharness: 2017-11-28 10:43:36.655987Z] Running read-buildbot-config step.
02:43:36 INFO - Running pre-action listener: _resource_record_pre_action
02:43:36 INFO - Running main action method: read_buildbot_config
02:43:36 INFO - Using buildbot properties:
02:43:36 INFO - {
02:43:36 INFO - "project": "",
02:43:36 INFO - "product": "firefox",
02:43:36 INFO - "who": "cbrindusan@mozilla.com",
02:43:36 INFO - "installer_path": "public/build/target.tar.bz2",
02:43:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
02:43:36 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
02:43:36 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
02:43:36 INFO - "stage_platform": "linux64-qr",
02:43:36 INFO - "basedir": "/builds/slave/test",
02:43:36 INFO - "buildnumber": 238,
02:43:36 INFO - "platform": "ubuntu64_hw_qr",
02:43:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
02:43:36 INFO - "slavebuilddir": "test",
02:43:36 INFO - "taskId": "AOVJPMcsSDSa72AZc1TSAg",
02:43:36 INFO - "branch": "mozilla-central",
02:43:36 INFO - "script_repo_revision": "production",
02:43:36 INFO - "revision": "cff54d05bcad74654249e2a2d4735b491bc452fd",
02:43:36 INFO - "slavename": "talos-linux64-ix-056",
02:43:36 INFO - "repo_path": "mozilla-central"
02:43:36 INFO - }
02:43:36 INFO - Finding installer, test and symbols from parent task.
02:43:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AOVJPMcsSDSa72AZc1TSAg'}, attempt #1
02:43:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AOVJPMcsSDSa72AZc1TSAg'}, attempt #1
02:43:37 INFO - Task dependencies: SoycHxfBTgixI_jqAvC36w
02:43:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w'}, attempt #1
02:43:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2
02:43:38 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.test_packages.json
02:43:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.crashreporter-symbols.zip
02:43:38 INFO - Running post-action listener: _resource_record_post_action
02:43:38 INFO - [mozharness: 2017-11-28 10:43:38.274632Z] Finished read-buildbot-config step (success)
02:43:38 INFO - [mozharness: 2017-11-28 10:43:38.274846Z] Running download-and-extract step.
02:43:38 INFO - Running pre-action listener: _resource_record_pre_action
02:43:38 INFO - Running main action method: download_and_extract
02:43:38 INFO - mkdir: /builds/slave/test/build/tests
02:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:43:38 INFO - trying https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.test_packages.json
02:43:38 INFO - Downloading https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
02:43:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
02:43:38 INFO - Downloaded 989 bytes.
02:43:38 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
02:43:38 INFO - Using the following test package requirements:
02:43:38 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
02:43:38 INFO - u'common': [u'target.common.tests.zip'],
02:43:38 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
02:43:38 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
02:43:38 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
02:43:38 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
02:43:38 INFO - u'mozbase': [u'target.common.tests.zip'],
02:43:38 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
02:43:38 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
02:43:38 INFO - u'web-platform': [u'target.common.tests.zip',
02:43:38 INFO - u'target.web-platform.tests.tar.gz'],
02:43:38 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
02:43:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
02:43:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.common.tests.zip
02:43:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.common.tests.zip'}, attempt #1
02:43:38 INFO - Fetch https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.common.tests.zip into memory
02:43:39 INFO - Content-Length response header: 59113646
02:43:39 INFO - Bytes received: 59113646
02:43:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.talos.tests.zip
02:43:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.talos.tests.zip'}, attempt #1
02:43:51 INFO - Fetch https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.talos.tests.zip into memory
02:43:51 INFO - Content-Length response header: 18744795
02:43:51 INFO - Bytes received: 18744795
02:43:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:43:52 INFO - trying https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2
02:43:52 INFO - Downloading https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
02:43:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
02:43:53 INFO - Downloaded 62614704 bytes.
02:43:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2
02:43:53 INFO - mkdir: /builds/slave/test/properties
02:43:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
02:43:53 INFO - Writing to file /builds/slave/test/properties/build_url
02:43:53 INFO - Contents:
02:43:53 INFO - build_url:https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2
02:43:53 INFO - Running post-action listener: _resource_record_post_action
02:43:53 INFO - Running post-action listener: find_tests_for_verification
02:43:53 INFO - Running post-action listener: set_extra_try_arguments
02:43:53 INFO - [mozharness: 2017-11-28 10:43:53.555361Z] Finished download-and-extract step (success)
02:43:53 INFO - [mozharness: 2017-11-28 10:43:53.555455Z] Running populate-webroot step.
02:43:53 INFO - Running pre-action listener: _resource_record_pre_action
02:43:53 INFO - Running main action method: populate_webroot
02:43:53 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
02:43:53 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['tresize',
02:43:53 INFO - 'tcanvasmark']},
02:43:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
02:43:53 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['dromaeo_css',
02:43:53 INFO - 'kraken']},
02:43:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:43:53 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:43:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'tests': ['damp', 'tps']},
02:43:53 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['damp', 'tps']},
02:43:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
02:43:53 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['dromaeo_dom']},
02:43:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
02:43:53 INFO - 'glvideo',
02:43:53 INFO - 'displaylist_mutate']},
02:43:53 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['basic_compositor_video',
02:43:53 INFO - 'glvideo']},
02:43:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
02:43:53 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['ts_paint_webext',
02:43:53 INFO - 'tp5o_webext']},
02:43:53 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
02:43:53 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:43:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:43:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:43:53 INFO - 'talos_options': ['--mitmproxy',
02:43:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:43:53 INFO - '--firstNonBlankPaint'],
02:43:53 INFO - 'tests': ['tp6_google_heavy',
02:43:53 INFO - 'tp6_youtube_heavy',
02:43:53 INFO - 'tp6_amazon_heavy',
02:43:53 INFO - 'tp6_facebook_heavy']},
02:43:53 INFO - 'other-e10s': {'tests': ['a11yr',
02:43:53 INFO - 'ts_paint',
02:43:53 INFO - 'tpaint',
02:43:53 INFO - 'sessionrestore',
02:43:53 INFO - 'sessionrestore_many_windows',
02:43:53 INFO - 'sessionrestore_no_auto_restore',
02:43:53 INFO - 'tabpaint',
02:43:53 INFO - 'cpstartup']},
02:43:53 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['a11yr',
02:43:53 INFO - 'ts_paint',
02:43:53 INFO - 'tpaint',
02:43:53 INFO - 'sessionrestore',
02:43:53 INFO - 'sessionrestore_many_windows',
02:43:53 INFO - 'sessionrestore_no_auto_restore',
02:43:53 INFO - 'tabpaint',
02:43:53 INFO - 'cpstartup']},
02:43:53 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
02:43:53 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
02:43:53 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['perf_reftest_singletons']},
02:43:53 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['perf_reftest']},
02:43:53 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
02:43:53 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['speedometer']},
02:43:53 INFO - 'svgr-e10s': {'tests': ['tsvgx',
02:43:53 INFO - 'tsvgr_opacity',
02:43:53 INFO - 'tart',
02:43:53 INFO - 'tscrollx',
02:43:53 INFO - 'tsvg_static']},
02:43:53 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['tsvgx',
02:43:53 INFO - 'tsvgr_opacity',
02:43:53 INFO - 'tart',
02:43:53 INFO - 'tscrollx',
02:43:53 INFO - 'tsvg_static']},
02:43:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
02:43:53 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'talos_options': ['--disable-stylo'],
02:43:53 INFO - 'tests': ['tp5o']},
02:43:53 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:43:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:43:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:43:53 INFO - 'talos_options': ['--mitmproxy',
02:43:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:43:53 INFO - '--firstNonBlankPaint'],
02:43:53 INFO - 'tests': ['tp6_google',
02:43:53 INFO - 'tp6_youtube',
02:43:53 INFO - 'tp6_amazon',
02:43:53 INFO - 'tp6_facebook']},
02:43:53 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:43:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:43:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:43:53 INFO - 'talos_options': ['--disable-stylo',
02:43:53 INFO - '--mitmproxy',
02:43:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:43:53 INFO - '--firstNonBlankPaint'],
02:43:53 INFO - 'tests': ['tp6_google',
02:43:53 INFO - 'tp6_youtube',
02:43:53 INFO - 'tp6_amazon',
02:43:53 INFO - 'tp6_facebook']},
02:43:53 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:43:53 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:43:53 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:43:53 INFO - 'talos_options': ['--stylo-threads=1',
02:43:53 INFO - '--mitmproxy',
02:43:53 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:43:53 INFO - '--firstNonBlankPaint'],
02:43:53 INFO - 'tests': ['tp6_google',
02:43:53 INFO - 'tp6_youtube',
02:43:53 INFO - 'tp6_amazon',
02:43:53 INFO - 'tp6_facebook']},
02:43:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'talos_options': ['--xperf_path',
02:43:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:43:53 INFO - 'tests': ['tp5n']},
02:43:53 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:43:53 INFO - 'talos_options': ['--disable-stylo',
02:43:53 INFO - '--xperf_path',
02:43:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:43:53 INFO - 'tests': ['tp5n']}}}
02:43:53 INFO - Running post-action listener: _resource_record_post_action
02:43:53 INFO - [mozharness: 2017-11-28 10:43:53.573854Z] Finished populate-webroot step (success)
02:43:53 INFO - [mozharness: 2017-11-28 10:43:53.573946Z] Running create-virtualenv step.
02:43:53 INFO - Running pre-action listener: _resource_record_pre_action
02:43:53 INFO - Running main action method: create_virtualenv
02:43:53 INFO - Creating virtualenv /builds/slave/test/build/venv
02:43:53 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
02:43:53 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
02:43:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
02:43:54 INFO - Using real prefix '/usr'
02:43:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python
02:43:54 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
02:43:58 INFO - Installing setuptools, pip, wheel...done.
02:43:58 INFO - Return code: 0
02:43:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
02:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:43:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:43:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
02:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
02:43:58 INFO - Using env: {'DISPLAY': ':0',
02:43:58 INFO - 'HOME': '/home/cltbld',
02:43:58 INFO - 'LANG': 'en_US.UTF-8',
02:43:58 INFO - 'LANGUAGE': 'en_US:en',
02:43:58 INFO - 'LOGNAME': 'cltbld',
02:43:58 INFO - 'MAIL': '/var/mail/cltbld',
02:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:43:58 INFO - 'MOZ_NO_REMOTE': '1',
02:43:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:43:58 INFO - 'NO_EM_RESTART': '1',
02:43:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:43:58 INFO - 'PWD': '/builds/slave/test',
02:43:58 INFO - 'SHELL': '/bin/bash',
02:43:58 INFO - 'SHLVL': '1',
02:43:58 INFO - 'TERM': 'linux',
02:43:58 INFO - 'TMOUT': '86400',
02:43:58 INFO - 'USER': 'cltbld',
02:43:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:43:58 INFO - '_': '/tools/buildbot/bin/python'}
02:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple
02:43:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
02:43:58 INFO - Return code: 0
02:43:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
02:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:43:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:43:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:43:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:43:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:43:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
02:43:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
02:43:58 INFO - Using env: {'DISPLAY': ':0',
02:43:58 INFO - 'HOME': '/home/cltbld',
02:43:58 INFO - 'LANG': 'en_US.UTF-8',
02:43:58 INFO - 'LANGUAGE': 'en_US:en',
02:43:58 INFO - 'LOGNAME': 'cltbld',
02:43:58 INFO - 'MAIL': '/var/mail/cltbld',
02:43:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:43:58 INFO - 'MOZ_NO_REMOTE': '1',
02:43:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:43:58 INFO - 'NO_EM_RESTART': '1',
02:43:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:43:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:43:58 INFO - 'PWD': '/builds/slave/test',
02:43:58 INFO - 'SHELL': '/bin/bash',
02:43:58 INFO - 'SHLVL': '1',
02:43:58 INFO - 'TERM': 'linux',
02:43:58 INFO - 'TMOUT': '86400',
02:43:58 INFO - 'USER': 'cltbld',
02:43:58 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:43:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:43:58 INFO - '_': '/tools/buildbot/bin/python'}
02:43:59 INFO - Ignoring indexes: https://pypi.python.org/simple
02:43:59 INFO - Collecting psutil>=3.1.1
02:44:00 INFO - Installing collected packages: psutil
02:44:00 INFO - Successfully installed psutil-3.1.1
02:44:00 INFO - Return code: 0
02:44:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
02:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:00 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
02:44:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
02:44:00 INFO - Using env: {'DISPLAY': ':0',
02:44:00 INFO - 'HOME': '/home/cltbld',
02:44:00 INFO - 'LANG': 'en_US.UTF-8',
02:44:00 INFO - 'LANGUAGE': 'en_US:en',
02:44:00 INFO - 'LOGNAME': 'cltbld',
02:44:00 INFO - 'MAIL': '/var/mail/cltbld',
02:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:00 INFO - 'MOZ_NO_REMOTE': '1',
02:44:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:00 INFO - 'NO_EM_RESTART': '1',
02:44:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:00 INFO - 'PWD': '/builds/slave/test',
02:44:00 INFO - 'SHELL': '/bin/bash',
02:44:00 INFO - 'SHLVL': '1',
02:44:00 INFO - 'TERM': 'linux',
02:44:00 INFO - 'TMOUT': '86400',
02:44:00 INFO - 'USER': 'cltbld',
02:44:00 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:00 INFO - '_': '/tools/buildbot/bin/python'}
02:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:00 INFO - Collecting mozsystemmonitor==0.3
02:44:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
02:44:01 INFO - Installing collected packages: mozsystemmonitor
02:44:01 INFO - Successfully installed mozsystemmonitor-0.3
02:44:01 INFO - Return code: 0
02:44:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
02:44: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')]}
02:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44: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')]}
02:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:01 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
02:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
02:44:01 INFO - Using env: {'DISPLAY': ':0',
02:44:01 INFO - 'HOME': '/home/cltbld',
02:44:01 INFO - 'LANG': 'en_US.UTF-8',
02:44:01 INFO - 'LANGUAGE': 'en_US:en',
02:44:01 INFO - 'LOGNAME': 'cltbld',
02:44:01 INFO - 'MAIL': '/var/mail/cltbld',
02:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:01 INFO - 'MOZ_NO_REMOTE': '1',
02:44:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:01 INFO - 'NO_EM_RESTART': '1',
02:44:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:01 INFO - 'PWD': '/builds/slave/test',
02:44:01 INFO - 'SHELL': '/bin/bash',
02:44:01 INFO - 'SHLVL': '1',
02:44:01 INFO - 'TERM': 'linux',
02:44:01 INFO - 'TMOUT': '86400',
02:44:01 INFO - 'USER': 'cltbld',
02:44:01 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:01 INFO - '_': '/tools/buildbot/bin/python'}
02:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:02 INFO - Collecting jsonschema==2.5.1
02:44:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
02:44:03 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
02:44:05 INFO - Installing collected packages: functools32, jsonschema
02:44:05 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
02:44:05 INFO - Return code: 0
02:44:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
02:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:05 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
02:44:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
02:44:05 INFO - Using env: {'DISPLAY': ':0',
02:44:05 INFO - 'HOME': '/home/cltbld',
02:44:05 INFO - 'LANG': 'en_US.UTF-8',
02:44:05 INFO - 'LANGUAGE': 'en_US:en',
02:44:05 INFO - 'LOGNAME': 'cltbld',
02:44:05 INFO - 'MAIL': '/var/mail/cltbld',
02:44:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:05 INFO - 'MOZ_NO_REMOTE': '1',
02:44:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:05 INFO - 'NO_EM_RESTART': '1',
02:44:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:05 INFO - 'PWD': '/builds/slave/test',
02:44:05 INFO - 'SHELL': '/bin/bash',
02:44:05 INFO - 'SHLVL': '1',
02:44:05 INFO - 'TERM': 'linux',
02:44:05 INFO - 'TMOUT': '86400',
02:44:05 INFO - 'USER': 'cltbld',
02:44:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:05 INFO - '_': '/tools/buildbot/bin/python'}
02:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
02:44:06 INFO - Return code: 0
02:44:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
02:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:06 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
02:44:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
02:44:06 INFO - Using env: {'DISPLAY': ':0',
02:44:06 INFO - 'HOME': '/home/cltbld',
02:44:06 INFO - 'LANG': 'en_US.UTF-8',
02:44:06 INFO - 'LANGUAGE': 'en_US:en',
02:44:06 INFO - 'LOGNAME': 'cltbld',
02:44:06 INFO - 'MAIL': '/var/mail/cltbld',
02:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:06 INFO - 'MOZ_NO_REMOTE': '1',
02:44:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:06 INFO - 'NO_EM_RESTART': '1',
02:44:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:06 INFO - 'PWD': '/builds/slave/test',
02:44:06 INFO - 'SHELL': '/bin/bash',
02:44:06 INFO - 'SHLVL': '1',
02:44:06 INFO - 'TERM': 'linux',
02:44:06 INFO - 'TMOUT': '86400',
02:44:06 INFO - 'USER': 'cltbld',
02:44:06 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:06 INFO - '_': '/tools/buildbot/bin/python'}
02:44:06 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:06 INFO - Collecting blobuploader==1.2.4
02:44:07 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
02:44:08 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
02:44:09 INFO - Installing collected packages: requests, docopt, blobuploader
02:44:10 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
02:44:10 INFO - Return code: 0
02:44:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:10 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
02:44:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:44:10 INFO - Using env: {'DISPLAY': ':0',
02:44:10 INFO - 'HOME': '/home/cltbld',
02:44:10 INFO - 'LANG': 'en_US.UTF-8',
02:44:10 INFO - 'LANGUAGE': 'en_US:en',
02:44:10 INFO - 'LOGNAME': 'cltbld',
02:44:10 INFO - 'MAIL': '/var/mail/cltbld',
02:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:10 INFO - 'MOZ_NO_REMOTE': '1',
02:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:10 INFO - 'NO_EM_RESTART': '1',
02:44:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:10 INFO - 'PWD': '/builds/slave/test',
02:44:10 INFO - 'SHELL': '/bin/bash',
02:44:10 INFO - 'SHLVL': '1',
02:44:10 INFO - 'TERM': 'linux',
02:44:10 INFO - 'TMOUT': '86400',
02:44:10 INFO - 'USER': 'cltbld',
02:44:10 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:10 INFO - '_': '/tools/buildbot/bin/python'}
02:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:10 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:44:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:44:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:44:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:44:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:44:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:44:12 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:44:12 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:44:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:44:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:44:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:44:13 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:44:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:44:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:44:13 INFO - Running setup.py install for manifestparser: started
02:44:14 INFO - Running setup.py install for manifestparser: finished with status 'done'
02:44:14 INFO - Running setup.py install for mozcrash: started
02:44:14 INFO - Running setup.py install for mozcrash: finished with status 'done'
02:44:14 INFO - Running setup.py install for mozdebug: started
02:44:14 INFO - Running setup.py install for mozdebug: finished with status 'done'
02:44:14 INFO - Running setup.py install for mozdevice: started
02:44:14 INFO - Running setup.py install for mozdevice: finished with status 'done'
02:44:14 INFO - Running setup.py install for mozfile: started
02:44:14 INFO - Running setup.py install for mozfile: finished with status 'done'
02:44:14 INFO - Running setup.py install for mozhttpd: started
02:44:15 INFO - Running setup.py install for mozhttpd: finished with status 'done'
02:44:15 INFO - Running setup.py install for mozinfo: started
02:44:15 INFO - Running setup.py install for mozinfo: finished with status 'done'
02:44:15 INFO - Running setup.py install for mozInstall: started
02:44:15 INFO - Running setup.py install for mozInstall: finished with status 'done'
02:44:15 INFO - Running setup.py install for mozleak: started
02:44:15 INFO - Running setup.py install for mozleak: finished with status 'done'
02:44:15 INFO - Running setup.py install for mozlog: started
02:44:16 INFO - Running setup.py install for mozlog: finished with status 'done'
02:44:16 INFO - Running setup.py install for moznetwork: started
02:44:16 INFO - Running setup.py install for moznetwork: finished with status 'done'
02:44:16 INFO - Running setup.py install for mozprocess: started
02:44:16 INFO - Running setup.py install for mozprocess: finished with status 'done'
02:44:16 INFO - Running setup.py install for mozprofile: started
02:44:16 INFO - Running setup.py install for mozprofile: finished with status 'done'
02:44:16 INFO - Running setup.py install for mozrunner: started
02:44:16 INFO - Running setup.py install for mozrunner: finished with status 'done'
02:44:17 INFO - Running setup.py install for mozscreenshot: started
02:44:17 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
02:44:17 INFO - Running setup.py install for moztest: started
02:44:17 INFO - Running setup.py install for moztest: finished with status 'done'
02:44:17 INFO - Running setup.py install for mozversion: started
02:44:17 INFO - Running setup.py install for mozversion: finished with status 'done'
02:44:17 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.14 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
02:44:17 INFO - Return code: 0
02:44:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:17 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
02:44:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:44:17 INFO - Using env: {'DISPLAY': ':0',
02:44:17 INFO - 'HOME': '/home/cltbld',
02:44:17 INFO - 'LANG': 'en_US.UTF-8',
02:44:17 INFO - 'LANGUAGE': 'en_US:en',
02:44:17 INFO - 'LOGNAME': 'cltbld',
02:44:17 INFO - 'MAIL': '/var/mail/cltbld',
02:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:17 INFO - 'MOZ_NO_REMOTE': '1',
02:44:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:17 INFO - 'NO_EM_RESTART': '1',
02:44:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:17 INFO - 'PWD': '/builds/slave/test',
02:44:17 INFO - 'SHELL': '/bin/bash',
02:44:17 INFO - 'SHLVL': '1',
02:44:17 INFO - 'TERM': 'linux',
02:44:17 INFO - 'TMOUT': '86400',
02:44:17 INFO - 'USER': 'cltbld',
02:44:17 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:17 INFO - '_': '/tools/buildbot/bin/python'}
02:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:18 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:44:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:44:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
02:44:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:44:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
02:44:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
02:44:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:44:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
02:44:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
02:44:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
02:44:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:44:20 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
02:44:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:44:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
02:44:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
02:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.14 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
02:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
02:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
02:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:44:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
02:44:21 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:44:23 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
02:44:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:44:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:44:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
02:44:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:44:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:44:23 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
02:44:23 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:44:24 INFO - Installing collected packages: six, blessings
02:44:24 INFO - Successfully installed blessings-1.6 six-1.10.0
02:44:24 INFO - Return code: 0
02:44:24 INFO - Done creating virtualenv /builds/slave/test/build/venv.
02:44:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:44:24 INFO - Reading from file tmpfile_stdout
02:44:24 INFO - Reading from file tmpfile_stderr
02:44:24 INFO - Current package versions:
02:44:24 INFO - blessings == 1.6
02:44:24 INFO - blobuploader == 1.2.4
02:44:24 INFO - docopt == 0.6.1
02:44:24 INFO - functools32 == 3.2.3.post2
02:44:24 INFO - jsonschema == 2.5.1
02:44:24 INFO - manifestparser == 1.2
02:44:24 INFO - mozInstall == 1.14
02:44:24 INFO - mozcrash == 1.0
02:44:24 INFO - mozdebug == 0.1
02:44:24 INFO - mozdevice == 0.51
02:44:24 INFO - mozfile == 1.2
02:44:24 INFO - mozhttpd == 0.7
02:44:24 INFO - mozinfo == 0.10
02:44:24 INFO - mozleak == 0.1
02:44:24 INFO - mozlog == 3.5
02:44:24 INFO - moznetwork == 0.27
02:44:24 INFO - mozprocess == 0.25
02:44:24 INFO - mozprofile == 0.29
02:44:24 INFO - mozrunner == 6.14
02:44:24 INFO - mozscreenshot == 0.1
02:44:24 INFO - mozsystemmonitor == 0.3
02:44:24 INFO - moztest == 0.8
02:44:24 INFO - mozversion == 1.4
02:44:24 INFO - psutil == 3.1.1
02:44:24 INFO - requests == 1.2.3
02:44:24 INFO - six == 1.10.0
02:44:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:44: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')]}
02:44:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44: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')]}
02:44:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:24 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
02:44:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:44:24 INFO - Using env: {'DISPLAY': ':0',
02:44:25 INFO - 'HOME': '/home/cltbld',
02:44:25 INFO - 'LANG': 'en_US.UTF-8',
02:44:25 INFO - 'LANGUAGE': 'en_US:en',
02:44:25 INFO - 'LOGNAME': 'cltbld',
02:44:25 INFO - 'MAIL': '/var/mail/cltbld',
02:44:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:25 INFO - 'MOZ_NO_REMOTE': '1',
02:44:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:25 INFO - 'NO_EM_RESTART': '1',
02:44:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:25 INFO - 'PWD': '/builds/slave/test',
02:44:25 INFO - 'SHELL': '/bin/bash',
02:44:25 INFO - 'SHLVL': '1',
02:44:25 INFO - 'TERM': 'linux',
02:44:25 INFO - 'TMOUT': '86400',
02:44:25 INFO - 'USER': 'cltbld',
02:44:25 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:25 INFO - '_': '/tools/buildbot/bin/python'}
02:44:25 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
02:44:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
02:44:25 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
02:44:26 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:44:27 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
02:44:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
02:44:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
02:44:27 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
02:44:27 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))
02:44:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
02:44:28 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:44:29 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
02:44:29 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:44:30 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
02:44:30 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:44:31 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
02:44:31 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
02:44:32 INFO - Found existing installation: requests 1.2.3
02:44:32 INFO - Uninstalling requests-1.2.3:
02:44:32 INFO - Successfully uninstalled requests-1.2.3
02:44:32 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
02:44:32 INFO - Return code: 0
02:44:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
02:44: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')]}
02:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:44: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')]}
02:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:44:32 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 0xce7d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcc6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe684c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, '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': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:44:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
02:44:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
02:44:32 INFO - Using env: {'DISPLAY': ':0',
02:44:32 INFO - 'HOME': '/home/cltbld',
02:44:32 INFO - 'LANG': 'en_US.UTF-8',
02:44:32 INFO - 'LANGUAGE': 'en_US:en',
02:44:32 INFO - 'LOGNAME': 'cltbld',
02:44:32 INFO - 'MAIL': '/var/mail/cltbld',
02:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:32 INFO - 'MOZ_NO_REMOTE': '1',
02:44:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:32 INFO - 'NO_EM_RESTART': '1',
02:44:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:32 INFO - 'PWD': '/builds/slave/test',
02:44:32 INFO - 'SHELL': '/bin/bash',
02:44:32 INFO - 'SHLVL': '1',
02:44:32 INFO - 'TERM': 'linux',
02:44:32 INFO - 'TMOUT': '86400',
02:44:32 INFO - 'USER': 'cltbld',
02:44:32 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:32 INFO - '_': '/tools/buildbot/bin/python'}
02:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple
02:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
02:44:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
02:44:33 INFO - Return code: 0
02:44:33 INFO - Running post-action listener: _resource_record_post_action
02:44:33 INFO - Running post-action listener: _start_resource_monitoring
02:44:33 INFO - Starting resource monitoring.
02:44:33 INFO - [mozharness: 2017-11-28 10:44:33.042441Z] Finished create-virtualenv step (success)
02:44:33 INFO - [mozharness: 2017-11-28 10:44:33.042913Z] Running install step.
02:44:33 INFO - Running pre-action listener: _resource_record_pre_action
02:44:33 INFO - Running main action method: install
02:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:44:33 INFO - Reading from file tmpfile_stdout
02:44:33 INFO - Reading from file tmpfile_stderr
02:44:33 INFO - Detecting whether we're running mozinstall >=1.0...
02:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
02:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
02:44:33 INFO - Reading from file tmpfile_stdout
02:44:33 INFO - Output received:
02:44:33 INFO - Usage: mozinstall [options] installer
02:44:33 INFO - Options:
02:44:33 INFO - -h, --help show this help message and exit
02:44:33 INFO - -d DEST, --destination=DEST
02:44:33 INFO - Directory to install application into. [default:
02:44:33 INFO - "/builds/slave/test"]
02:44:33 INFO - --app=APP Application being installed. [default: firefox]
02:44:33 INFO - mkdir: /builds/slave/test/build/application
02:44:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
02:44:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
02:44:52 INFO - Reading from file tmpfile_stdout
02:44:52 INFO - Output received:
02:44:52 INFO - /builds/slave/test/build/application/firefox/firefox
02:44:52 INFO - Running post-action listener: _resource_record_post_action
02:44:52 INFO - [mozharness: 2017-11-28 10:44:52.101913Z] Finished install step (success)
02:44:52 INFO - [mozharness: 2017-11-28 10:44:52.102155Z] Running setup-mitmproxy step.
02:44:52 INFO - Running pre-action listener: _resource_record_pre_action
02:44:52 INFO - Running main action method: setup_mitmproxy
02:44:52 INFO - Skipping: mitmproxy is not required
02:44:52 INFO - Running post-action listener: _resource_record_post_action
02:44:52 INFO - [mozharness: 2017-11-28 10:44:52.103252Z] Finished setup-mitmproxy step (success)
02:44:52 INFO - [mozharness: 2017-11-28 10:44:52.103466Z] Running run-tests step.
02:44:52 INFO - Running pre-action listener: _resource_record_pre_action
02:44:52 INFO - Running pre-action listener: _set_gcov_prefix
02:44:52 INFO - Running main action method: run_tests
02:44:52 WARNING - Try message not found.
02:44:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
02:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
02:44:52 INFO - Python 2.7.3
02:44:52 INFO - Return code: 0
02:44:52 INFO - grabbing minidump binary from tooltool
02:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:44:52 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 0xe65660>, '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 0xe66560>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe669f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
02:44:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
02:44:52 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
02:44:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
02:44:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
02:44:52 INFO - Return code: 0
02:44:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
02:44:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
02:44:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
02:44:52 INFO - ENV: RUST_BACKTRACE is now full
02:44:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
02:44:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
02:44:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
02:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-056 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
02:44:52 INFO - Using env: {'DISPLAY': ':0',
02:44:52 INFO - 'HOME': '/home/cltbld',
02:44:52 INFO - 'LANG': 'en_US.UTF-8',
02:44:52 INFO - 'LANGUAGE': 'en_US:en',
02:44:52 INFO - 'LOGNAME': 'cltbld',
02:44:52 INFO - 'MAIL': '/var/mail/cltbld',
02:44:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:44:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:44:52 INFO - 'MOZ_ACCELERATED': '1',
02:44:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:44:52 INFO - 'MOZ_NO_REMOTE': '1',
02:44:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:44:52 INFO - 'MOZ_WEBRENDER': '1',
02:44:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:44:52 INFO - 'NO_EM_RESTART': '1',
02:44:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:44:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:44:52 INFO - 'PWD': '/builds/slave/test',
02:44:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:44:52 INFO - 'RUST_BACKTRACE': 'full',
02:44:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:44:52 INFO - 'SHELL': '/bin/bash',
02:44:52 INFO - 'SHLVL': '1',
02:44:52 INFO - 'TERM': 'linux',
02:44:52 INFO - 'TMOUT': '86400',
02:44:52 INFO - 'USER': 'cltbld',
02:44:52 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:44:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:44:52 INFO - '_': '/tools/buildbot/bin/python'}
02:44:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-056', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
02:44:52 INFO - mozversion application_buildid: 20171128094829
02:44:52 INFO - mozversion application_changeset: cff54d05bcad74654249e2a2d4735b491bc452fd
02:44:52 INFO - mozversion application_display_name: Nightly
02:44:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:44:52 INFO - mozversion application_name: Firefox
02:44:52 INFO - mozversion application_remotingname: firefox
02:44:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
02:44:52 INFO - mozversion application_vendor: Mozilla
02:44:52 INFO - mozversion application_version: 59.0a1
02:44:52 INFO - mozversion platform_buildid: 20171128094829
02:44:52 INFO - mozversion platform_changeset: cff54d05bcad74654249e2a2d4735b491bc452fd
02:44:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
02:44:52 INFO - mozversion platform_version: 59.0a1
02:44:52 INFO - using testdate: 1511865892
02:44:52 INFO - actual date: 1511865892
02:44:52 INFO - starting webserver on 'localhost:49853'
02:44:52 INFO - SUITE-START | Running 3 tests
02:44:52 INFO - TEST-START | basic_compositor_video
02:44:52 INFO - Initialising browser for basic_compositor_video test...
02:44:52 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
02:44:52 INFO - Installing Add-ons
02:44:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49853/getInfo.html -profile /tmp/tmpw8Rexe/profile
02:44:52 INFO - TEST-INFO | started process 26304 (/builds/slave/test/build/application/firefox/firefox http://localhost:49853/getInfo.html)
02:45:07 INFO - TEST-INFO | 26304: exit 0
02:45:07 INFO - Browser initialized.
02:45:07 INFO - Running cycle 1/1 for basic_compositor_video test...
02:45:07 INFO - Using env: {'DISPLAY': ':0',
02:45:07 INFO - 'HOME': '/home/cltbld',
02:45:07 INFO - 'JSGC_DISABLE_POISONING': '1',
02:45:07 INFO - 'LANG': 'en_US.UTF-8',
02:45:07 INFO - 'LANGUAGE': 'en_US:en',
02:45:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:45:07 INFO - 'LOGNAME': 'cltbld',
02:45:07 INFO - 'MAIL': '/var/mail/cltbld',
02:45:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:45:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:45:07 INFO - 'MOZ_ACCELERATED': '1',
02:45:07 INFO - 'MOZ_CRASHREPORTER': '1',
02:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:45:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:45:07 INFO - 'MOZ_NO_REMOTE': '1',
02:45:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:45:07 INFO - 'MOZ_WEBRENDER': '1',
02:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:45:07 INFO - 'NO_EM_RESTART': '1',
02:45:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:45:07 INFO - 'PWD': '/builds/slave/test',
02:45:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:45:07 INFO - 'RUST_BACKTRACE': 'full',
02:45:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:45:07 INFO - 'SHELL': '/bin/bash',
02:45:07 INFO - 'SHLVL': '1',
02:45:07 INFO - 'TERM': 'linux',
02:45:07 INFO - 'TMOUT': '86400',
02:45:07 INFO - 'USER': 'cltbld',
02:45:07 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:45:07 INFO - '_': '/tools/buildbot/bin/python'}
02:45:07 INFO - TEST-INFO | started process 26645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw8Rexe/profile)
02:45:08 INFO - PID 26645 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:45:08 INFO - PID 26645 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:45:08 INFO - PID 26645 |
02:45:08 INFO - PID 26645 | (/builds/slave/test/build/application/firefox/firefox:26707): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:45:08 INFO - PID 26645 |
02:45:10 INFO - PID 26645 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:45:54 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6525773651635722 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.591029792746114 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.657488928255093 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.635797101449275 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2263440860215056 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2154099678456602 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.305107604017214 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.219957805907177 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.36836244541485 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.406013215859026 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.8870032573289945 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.854393203883491 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.324914634146342 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.0937765957446794 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.336356968215158 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.119572953736675 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.390024630541868 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.144214285714303 ms/frame
02:45:54 INFO - PID 26645 |
02:45:54 INFO - PID 26645 | Cycle 1(1): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:46:40 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.609591304347826 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.597623376623376 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6600797872340425 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.649019867549668 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2263010752688164 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2168890675241166 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.279950071326677 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.256648936170213 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.304540889526542 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.183995815899583 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.8637601296596475 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.809218749999996 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.35332107843137 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.119412811387901 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.3353300733496365 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.120747330960855 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.282439320388344 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.092677304964546 ms/frame
02:46:40 INFO - PID 26645 |
02:46:40 INFO - PID 26645 | Cycle 1(2): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:47:26 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6073805386620332 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.595097276264591 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.657462356067316 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.642331571994717 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.226548387096775 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2158118971061103 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.3603633720930235 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.301548387096776 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.373352769679299 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.3584313725490205 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.847600969305333 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.786244019138751 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.335831295843529 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.0678975265017545 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.335036674816623 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.1194483985765205 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.323012195121946 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.143392857142862 ms/frame
02:47:26 INFO - PID 26645 |
02:47:26 INFO - PID 26645 | Cycle 1(3): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:48:12 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.623291083916084 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6062955729166664 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6478331862312445 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.642483487450462 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2232599355531684 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.215924437299036 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.348688405797101 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.311756465517247 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.566156773211568 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.5675913242009125 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.966812913907288 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.868454987834544 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.371756756756757 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.168584229390667 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.389679802955663 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.168279569892463 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.283822815533993 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.094485815602826 ms/frame
02:48:12 INFO - PID 26645 |
02:48:12 INFO - PID 26645 | Cycle 1(4): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:48:58 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6066203301476976 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5947211413748383 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.648089143865843 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.650304635761589 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.237092772384034 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.216374598070741 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.566248097412481 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.610241935483874 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.672928348909658 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.526459276018099 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.8471809369951515 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.785035885167465 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.371597051597056 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.11927046263345 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.3017153284671465 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.119412811387901 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.354705882352944 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.119306049822069 ms/frame
02:48:58 INFO - PID 26645 |
02:48:58 INFO - PID 26645 | Cycle 1(5): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:49:44 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6180715532286216 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5987597402597395 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6457098765432105 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6384696569920845 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2401565874730025 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2116452648475113 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.27969329529244 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.238506355932205 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.445925925925926 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.425099557522125 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.855250809061491 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.820445783132532 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.317463414634155 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.069929328621885 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.354558823529421 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.067773851590081 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.390147783251235 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.095656028368789 ms/frame
02:49:44 INFO - PID 26645 |
02:49:44 INFO - PID 26645 | Cycle 1(6): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:50:30 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6047569444444445 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.6046875000000003 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.655 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.642490092470277 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.21994635193133 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2160852090032166 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.273368945868947 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.202310924369747 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.511398496240604 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.348032608695647 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.8470032310177675 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.819746987951807 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.371400491400491 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.171648745519711 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.317768292682936 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.121032028469732 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.318646341463407 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.0789045936395825 ms/frame
02:50:30 INFO - PID 26645 |
02:50:30 INFO - PID 26645 | Cycle 1(7): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:51:16 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.606863596872285 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.601345903771131 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.652727674624226 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.646335978835979 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2261666666666677 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.220724637681159 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.673208722741431 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.641856148491886 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.249695467422096 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.158596673596674 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.816653290529698 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.775381861575175 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.319256097560976 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.0937943262411505 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.317780487804862 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.1176156583629995 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.3010948905109485 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.0927659574468205 ms/frame
02:51:16 INFO - PID 26645 |
02:51:16 INFO - PID 26645 | Cycle 1(8): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:52:02 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6156408020924147 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.59837012987013 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.648702559576346 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6458465608465622 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2189270386266102 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.215844051446944 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.601157975460122 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.486165919282515 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.498995502248873 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.48511210762332 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.855792880258901 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.785370813397129 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.33544009779951 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.067738515901054 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.284101941747562 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.06765017667846 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.372235872235872 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.069063604240276 ms/frame
02:52:02 INFO - PID 26645 |
02:52:02 INFO - PID 26645 | Cycle 1(9): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:52:48 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6229283216783217 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.598025974025974 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6510468197879864 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6393733509234836 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.2226960257787325 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.210433386837882 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.665816485225506 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.652593023255809 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.437892011834319 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.331060606060608 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.838959677419353 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.807668269230773 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.353517156862756 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.11953736654803 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.299464720194634 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.070830388692599 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.3357946210269045 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.093085106382978 ms/frame
02:52:48 INFO - PID 26645 |
02:52:48 INFO - PID 26645 | Cycle 1(10): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:53:34 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6067072111207645 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.5982077922077926 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.648186231244484 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.638806068601582 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.237195253505932 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2271935483870977 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.273611111111112 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.247292993630571 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.292238912732472 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.229693446088796 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.840241935483872 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.832379227053139 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.390849753694575 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.095425531914906 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.336283618581909 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.119181494661914 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.353517156862747 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.120943060498223 ms/frame
02:53:34 INFO - PID 26645 |
02:53:34 INFO - PID 26645 | Cycle 1(11): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:54:20 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_startup = 2.6117362924281986 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.601762028608582 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.6484466019417474 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.6392744063324547 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_startup = 3.22690322580645 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.2054727564102565 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_startup = 4.594532924961717 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1_inclip = 4.631655092592593 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.317899280575538 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.167135416666671 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_startup = 4.855242718446603 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.480p.60fps.webm_scale_2_inclip = 4.809843750000001 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.3195609756097575 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1_inclip = 7.118594306049812 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.301654501216553 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 7.121067615658352 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.317121951219504 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | testsrc.1080p.60fps.mp4_scale_2_inclip = 7.067402826855139 ms/frame
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | Cycle 1(12): loaded http://localhost:49853/tests/video/video_playback.html (next: http://localhost:49853/tests/video/video_playback.html)
02:54:20 INFO - PID 26645 | __start_tp_report
02:54:20 INFO - PID 26645 | _x_x_mozilla_page_load
02:54:20 INFO - PID 26645 | _x_x_mozilla_page_load_details
02:54:20 INFO - PID 26645 | |i|pagename|runs|
02:54:20 INFO - PID 26645 | |0;240p.120fps.mp4_scale_1_startup;2.6525773651635722;2.609591304347826;2.6073805386620332;2.623291083916084;2.6066203301476976;2.6180715532286216;2.6047569444444445;2.606863596872285;2.6156408020924147;2.6229283216783217;2.6067072111207645;2.6117362924281986
02:54:20 INFO - PID 26645 | |1;240p.120fps.mp4_scale_1_inclip;2.591029792746114;2.597623376623376;2.595097276264591;2.6062955729166664;2.5947211413748383;2.5987597402597395;2.6046875000000003;2.601345903771131;2.59837012987013;2.598025974025974;2.5982077922077926;2.601762028608582
02:54:20 INFO - PID 26645 | |2;240p.120fps.mp4_scale_1.1_startup;2.657488928255093;2.6600797872340425;2.657462356067316;2.6478331862312445;2.648089143865843;2.6457098765432105;2.655;2.652727674624226;2.648702559576346;2.6510468197879864;2.648186231244484;2.6484466019417474
02:54:20 INFO - PID 26645 | |3;240p.120fps.mp4_scale_1.1_inclip;2.635797101449275;2.649019867549668;2.642331571994717;2.642483487450462;2.650304635761589;2.6384696569920845;2.642490092470277;2.646335978835979;2.6458465608465622;2.6393733509234836;2.638806068601582;2.6392744063324547
02:54:20 INFO - PID 26645 | |4;240p.120fps.mp4_scale_2_startup;3.2263440860215056;3.2263010752688164;3.226548387096775;3.2232599355531684;3.237092772384034;3.2401565874730025;3.21994635193133;3.2261666666666677;3.2189270386266102;3.2226960257787325;3.237195253505932;3.22690322580645
02:54:20 INFO - PID 26645 | |5;240p.120fps.mp4_scale_2_inclip;3.2154099678456602;3.2168890675241166;3.2158118971061103;3.215924437299036;3.216374598070741;3.2116452648475113;3.2160852090032166;3.220724637681159;3.215844051446944;3.210433386837882;3.2271935483870977;3.2054727564102565
02:54:20 INFO - PID 26645 | |6;480p.60fps.webm_scale_1_startup;4.305107604017214;4.279950071326677;4.3603633720930235;4.348688405797101;4.566248097412481;4.27969329529244;4.273368945868947;4.673208722741431;4.601157975460122;4.665816485225506;4.273611111111112;4.594532924961717
02:54:20 INFO - PID 26645 | |7;480p.60fps.webm_scale_1_inclip;4.219957805907177;4.256648936170213;4.301548387096776;4.311756465517247;4.610241935483874;4.238506355932205;4.202310924369747;4.641856148491886;4.486165919282515;4.652593023255809;4.247292993630571;4.631655092592593
02:54:20 INFO - PID 26645 | |8;480p.60fps.webm_scale_1.1_startup;4.36836244541485;4.304540889526542;4.373352769679299;4.566156773211568;4.672928348909658;4.445925925925926;4.511398496240604;4.249695467422096;4.498995502248873;4.437892011834319;4.292238912732472;4.317899280575538
02:54:20 INFO - PID 26645 | |9;480p.60fps.webm_scale_1.1_inclip;4.406013215859026;4.183995815899583;4.3584313725490205;4.5675913242009125;4.526459276018099;4.425099557522125;4.348032608695647;4.158596673596674;4.48511210762332;4.331060606060608;4.229693446088796;4.167135416666671
02:54:20 INFO - PID 26645 | |10;480p.60fps.webm_scale_2_startup;4.8870032573289945;4.8637601296596475;4.847600969305333;4.966812913907288;4.8471809369951515;4.855250809061491;4.8470032310177675;4.816653290529698;4.855792880258901;4.838959677419353;4.840241935483872;4.855242718446603
02:54:20 INFO - PID 26645 | |11;480p.60fps.webm_scale_2_inclip;4.854393203883491;4.809218749999996;4.786244019138751;4.868454987834544;4.785035885167465;4.820445783132532;4.819746987951807;4.775381861575175;4.785370813397129;4.807668269230773;4.832379227053139;4.809843750000001
02:54:20 INFO - PID 26645 | |12;1080p.60fps.mp4_scale_1_startup;7.324914634146342;7.35332107843137;7.335831295843529;7.371756756756757;7.371597051597056;7.317463414634155;7.371400491400491;7.319256097560976;7.33544009779951;7.353517156862756;7.390849753694575;7.3195609756097575
02:54:20 INFO - PID 26645 | |13;1080p.60fps.mp4_scale_1_inclip;7.0937765957446794;7.119412811387901;7.0678975265017545;7.168584229390667;7.11927046263345;7.069929328621885;7.171648745519711;7.0937943262411505;7.067738515901054;7.11953736654803;7.095425531914906;7.118594306049812
02:54:20 INFO - PID 26645 | |14;1080p.60fps.mp4_scale_1.1_startup;7.336356968215158;7.3353300733496365;7.335036674816623;7.389679802955663;7.3017153284671465;7.354558823529421;7.317768292682936;7.317780487804862;7.284101941747562;7.299464720194634;7.336283618581909;7.301654501216553
02:54:20 INFO - PID 26645 | |15;1080p.60fps.mp4_scale_1.1_inclip;7.119572953736675;7.120747330960855;7.1194483985765205;7.168279569892463;7.119412811387901;7.067773851590081;7.121032028469732;7.1176156583629995;7.06765017667846;7.070830388692599;7.119181494661914;7.121067615658352
02:54:20 INFO - PID 26645 | |16;1080p.60fps.mp4_scale_2_startup;7.390024630541868;7.282439320388344;7.323012195121946;7.283822815533993;7.354705882352944;7.390147783251235;7.318646341463407;7.3010948905109485;7.372235872235872;7.3357946210269045;7.353517156862747;7.317121951219504
02:54:20 INFO - PID 26645 | |17;1080p.60fps.mp4_scale_2_inclip;7.144214285714303;7.092677304964546;7.143392857142862;7.094485815602826;7.119306049822069;7.095656028368789;7.0789045936395825;7.0927659574468205;7.069063604240276;7.093085106382978;7.120943060498223;7.067402826855139
02:54:20 INFO - PID 26645 | __end_tp_report
02:54:20 INFO - PID 26645 | __start_cc_report
02:54:20 INFO - PID 26645 | _x_x_mozilla_cycle_collect,558
02:54:20 INFO - PID 26645 | __end_cc_report
02:54:20 INFO - PID 26645 | __startTimestamp1511866460675__endTimestamp
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | ------- Summary: start -------
02:54:20 INFO - PID 26645 | Number of tests: 18
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.62 Median:2.61 stddev:0.01 (0.5%) stddev-sans-first:0.01
02:54:20 INFO - PID 26645 | Values: 2.7 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.60 Median:2.60 stddev:0.00 (0.2%) stddev-sans-first:0.00
02:54:20 INFO - PID 26645 | Values: 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6 2.6
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.65 Median:2.65 stddev:0.00 (0.2%) stddev-sans-first:0.00
02:54:20 INFO - PID 26645 | Values: 2.7 2.7 2.7 2.6 2.6 2.6 2.7 2.7 2.6 2.7 2.6 2.6
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.64 Median:2.64 stddev:0.00 (0.2%) stddev-sans-first:0.00
02:54:20 INFO - PID 26645 | Values: 2.6 2.6 2.6 2.6 2.7 2.6 2.6 2.6 2.6 2.6 2.6 2.6
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.23 Median:3.23 stddev:0.01 (0.2%) stddev-sans-first:0.01
02:54:20 INFO - PID 26645 | Values: 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.22 Median:3.22 stddev:0.01 (0.2%) stddev-sans-first:0.01
02:54:20 INFO - PID 26645 | Values: 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2 3.2
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.44 Median:4.46 stddev:0.17 (3.8%) stddev-sans-first:0.17
02:54:20 INFO - PID 26645 | Values: 4.3 4.3 4.4 4.3 4.6 4.3 4.3 4.7 4.6 4.7 4.3 4.6
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.40 Median:4.40 stddev:0.19 (4.3%) stddev-sans-first:0.19
02:54:20 INFO - PID 26645 | Values: 4.2 4.3 4.3 4.3 4.6 4.2 4.2 4.6 4.5 4.7 4.2 4.6
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.42 Median:4.44 stddev:0.13 (2.8%) stddev-sans-first:0.13
02:54:20 INFO - PID 26645 | Values: 4.4 4.3 4.4 4.6 4.7 4.4 4.5 4.2 4.5 4.4 4.3 4.3
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.35 Median:4.38 stddev:0.14 (3.2%) stddev-sans-first:0.15
02:54:20 INFO - PID 26645 | Values: 4.4 4.2 4.4 4.6 4.5 4.4 4.3 4.2 4.5 4.3 4.2 4.2
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.86 Median:4.86 stddev:0.04 (0.8%) stddev-sans-first:0.04
02:54:20 INFO - PID 26645 | Values: 4.9 4.9 4.8 5.0 4.8 4.9 4.8 4.8 4.9 4.8 4.8 4.9
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.81 Median:4.81 stddev:0.03 (0.6%) stddev-sans-first:0.03
02:54:20 INFO - PID 26645 | Values: 4.9 4.8 4.8 4.9 4.8 4.8 4.8 4.8 4.8 4.8 4.8 4.8
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.35 Median:7.35 stddev:0.03 (0.3%) stddev-sans-first:0.03
02:54:20 INFO - PID 26645 | Values: 7.3 7.4 7.3 7.4 7.4 7.3 7.4 7.3 7.3 7.4 7.4 7.3
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:7.11 Median:7.12 stddev:0.03 (0.5%) stddev-sans-first:0.04
02:54:20 INFO - PID 26645 | Values: 7.1 7.1 7.1 7.2 7.1 7.1 7.2 7.1 7.1 7.1 7.1 7.1
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:7.33 Median:7.34 stddev:0.03 (0.4%) stddev-sans-first:0.03
02:54:20 INFO - PID 26645 | Values: 7.3 7.3 7.3 7.4 7.3 7.4 7.3 7.3 7.3 7.3 7.3 7.3
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:7.11 Median:7.12 stddev:0.03 (0.4%) stddev-sans-first:0.03
02:54:20 INFO - PID 26645 | Values: 7.1 7.1 7.1 7.2 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.34 Median:7.34 stddev:0.04 (0.5%) stddev-sans-first:0.03
02:54:20 INFO - PID 26645 | Values: 7.4 7.3 7.3 7.3 7.4 7.4 7.3 7.3 7.4 7.3 7.4 7.3
02:54:20 INFO - PID 26645 |
02:54:20 INFO - PID 26645 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:7.10 Median:7.10 stddev:0.03 (0.4%) stddev-sans-first:0.02
02:54:20 INFO - PID 26645 | Values: 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1 7.1
02:54:20 INFO - PID 26645 | -------- Summary: end --------
02:54:20 INFO - PID 26645 |
02:54:26 INFO - TEST-INFO | 26645: exit 0
02:54:26 INFO - TEST-OK | basic_compositor_video | took 573936ms
02:54:26 INFO - TEST-START | glvideo
02:54:26 INFO - Initialising browser for glvideo test...
02:54:26 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
02:54:26 INFO - Installing Add-ons
02:54:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49853/getInfo.html -profile /tmp/tmpJC7aJN/profile
02:54:26 INFO - TEST-INFO | started process 27911 (/builds/slave/test/build/application/firefox/firefox http://localhost:49853/getInfo.html)
02:54:38 INFO - TEST-INFO | 27911: exit 0
02:54:38 INFO - Browser initialized.
02:54:38 INFO - Running cycle 1/1 for glvideo test...
02:54:38 INFO - Using env: {'DISPLAY': ':0',
02:54:38 INFO - 'HOME': '/home/cltbld',
02:54:38 INFO - 'JSGC_DISABLE_POISONING': '1',
02:54:38 INFO - 'LANG': 'en_US.UTF-8',
02:54:38 INFO - 'LANGUAGE': 'en_US:en',
02:54:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:54:38 INFO - 'LOGNAME': 'cltbld',
02:54:38 INFO - 'MAIL': '/var/mail/cltbld',
02:54:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:54:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:54:38 INFO - 'MOZ_ACCELERATED': '1',
02:54:38 INFO - 'MOZ_CRASHREPORTER': '1',
02:54:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:54:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:54:38 INFO - 'MOZ_NO_REMOTE': '1',
02:54:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:54:38 INFO - 'MOZ_WEBRENDER': '1',
02:54:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:54:38 INFO - 'NO_EM_RESTART': '1',
02:54:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:54:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:54:38 INFO - 'PWD': '/builds/slave/test',
02:54:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:54:38 INFO - 'RUST_BACKTRACE': 'full',
02:54:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:54:38 INFO - 'SHELL': '/bin/bash',
02:54:38 INFO - 'SHLVL': '1',
02:54:38 INFO - 'TERM': 'linux',
02:54:38 INFO - 'TMOUT': '86400',
02:54:38 INFO - 'USER': 'cltbld',
02:54:38 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:54:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:54:38 INFO - '_': '/tools/buildbot/bin/python'}
02:54:38 INFO - TEST-INFO | started process 28258 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJC7aJN/profile)
02:54:39 INFO - PID 28258 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:54:39 INFO - PID 28258 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:54:40 INFO - PID 28258 |
02:54:40 INFO - PID 28258 | (/builds/slave/test/build/application/firefox/firefox:28319): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:54:40 INFO - PID 28258 |
02:54:41 INFO - PID 28258 | [talos glvideo result] Mean tick time across 100 ticks: 6.9097 ms
02:54:41 INFO - PID 28258 | Cycle 1(1): loaded http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html)
02:54:41 INFO - PID 28258 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:54:42 INFO - PID 28258 | [talos glvideo result] Mean tick time across 100 ticks: 6.50885 ms
02:54:42 INFO - PID 28258 | Cycle 1(2): loaded http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html)
02:54:44 INFO - PID 28258 | [talos glvideo result] Mean tick time across 100 ticks: 6.567950000000001 ms
02:54:44 INFO - PID 28258 | Cycle 1(3): loaded http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html)
02:54:45 INFO - PID 28258 | [talos glvideo result] Mean tick time across 100 ticks: 6.6159 ms
02:54:45 INFO - PID 28258 | Cycle 1(4): loaded http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html)
02:54:46 INFO - PID 28258 | [talos glvideo result] Mean tick time across 100 ticks: 6.65035 ms
02:54:46 INFO - PID 28258 | Cycle 1(5): loaded http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49853/tests/webgl/benchmarks/video/video_upload.html)
02:54:47 INFO - PID 28258 | __start_tp_report
02:54:47 INFO - PID 28258 | _x_x_mozilla_page_load
02:54:47 INFO - PID 28258 | _x_x_mozilla_page_load_details
02:54:47 INFO - PID 28258 | |i|pagename|runs|
02:54:47 INFO - PID 28258 | |0;Mean tick time across 100 ticks: ;6.9097;6.50885;6.567950000000001;6.6159;6.65035
02:54:47 INFO - PID 28258 | __end_tp_report
02:54:47 INFO - PID 28258 | __start_cc_report
02:54:47 INFO - PID 28258 | _x_x_mozilla_cycle_collect,290
02:54:47 INFO - PID 28258 | __end_cc_report
02:54:47 INFO - PID 28258 | __startTimestamp1511866487196__endTimestamp
02:54:47 INFO - PID 28258 |
02:54:47 INFO - PID 28258 | ------- Summary: start -------
02:54:47 INFO - PID 28258 | Number of tests: 1
02:54:47 INFO - PID 28258 |
02:54:47 INFO - PID 28258 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.65 Median:6.62 stddev:0.15 (2.3%) stddev-sans-first:0.06
02:54:47 INFO - PID 28258 | Values: 6.9 6.5 6.6 6.6 6.7
02:54:47 INFO - PID 28258 | -------- Summary: end --------
02:54:47 INFO - PID 28258 |
02:54:47 INFO - PID 28258 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
02:54:53 INFO - TEST-INFO | 28258: exit 0
02:54:53 INFO - TEST-OK | glvideo | took 26677ms
02:54:53 INFO - TEST-START | displaylist_mutate
02:54:53 INFO - Initialising browser for displaylist_mutate test...
02:54:53 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
02:54:53 INFO - Installing Add-ons
02:54:53 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49853/getInfo.html -profile /tmp/tmpODOuCp/profile
02:54:53 INFO - TEST-INFO | started process 28495 (/builds/slave/test/build/application/firefox/firefox http://localhost:49853/getInfo.html)
02:55:05 INFO - TEST-INFO | 28495: exit 0
02:55:05 INFO - Browser initialized.
02:55:05 INFO - Running cycle 1/1 for displaylist_mutate test...
02:55:05 INFO - Using env: {'DISPLAY': ':0',
02:55:05 INFO - 'HOME': '/home/cltbld',
02:55:05 INFO - 'JSGC_DISABLE_POISONING': '1',
02:55:05 INFO - 'LANG': 'en_US.UTF-8',
02:55:05 INFO - 'LANGUAGE': 'en_US:en',
02:55:05 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:55:05 INFO - 'LOGNAME': 'cltbld',
02:55:05 INFO - 'MAIL': '/var/mail/cltbld',
02:55:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:55:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:55:05 INFO - 'MOZ_ACCELERATED': '1',
02:55:05 INFO - 'MOZ_CRASHREPORTER': '1',
02:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:55:05 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:55:05 INFO - 'MOZ_NO_REMOTE': '1',
02:55:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:55:05 INFO - 'MOZ_WEBRENDER': '1',
02:55:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:55:05 INFO - 'NO_EM_RESTART': '1',
02:55:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:55:05 INFO - 'PWD': '/builds/slave/test',
02:55:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:55:05 INFO - 'RUST_BACKTRACE': 'full',
02:55:05 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:55:05 INFO - 'SHELL': '/bin/bash',
02:55:05 INFO - 'SHLVL': '1',
02:55:05 INFO - 'TERM': 'linux',
02:55:05 INFO - 'TMOUT': '86400',
02:55:05 INFO - 'USER': 'cltbld',
02:55:05 INFO - 'XDG_SESSION_COOKIE': '52faae308a556c440401f8e5000001e8-1511865670.455565-785011975',
02:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:55:05 INFO - '_': '/tools/buildbot/bin/python'}
02:55:05 INFO - TEST-INFO | started process 28826 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpODOuCp/profile)
02:55:06 INFO - PID 28826 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:55:06 INFO - PID 28826 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:55:06 INFO - PID 28826 |
02:55:06 INFO - PID 28826 | (/builds/slave/test/build/application/firefox/firefox:28887): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:55:06 INFO - PID 28826 |
02:55:13 INFO - PID 28826 | Cycle 1(1): loaded http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html)
02:55:13 INFO - PID 28826 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:55:20 INFO - PID 28826 | Cycle 1(2): loaded http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html)
02:55:26 INFO - PID 28826 | Cycle 1(3): loaded http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html)
02:55:33 INFO - PID 28826 | Cycle 1(4): loaded http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html)
02:55:40 INFO - PID 28826 | Cycle 1(5): loaded http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:49853/tests/layout/benchmarks/displaylist_mutate.html)
02:55:41 INFO - PID 28826 | __start_tp_report
02:55:41 INFO - PID 28826 | _x_x_mozilla_page_load
02:55:41 INFO - PID 28826 | _x_x_mozilla_page_load_details
02:55:41 INFO - PID 28826 | |i|pagename|runs|
02:55:41 INFO - PID 28826 | |0;/displaylist_mutate.html;5874.055;6012.72;6152.845;6124.575000000001;6141.0650000000005
02:55:41 INFO - PID 28826 | __end_tp_report
02:55:41 INFO - PID 28826 | __start_cc_report
02:55:41 INFO - PID 28826 | _x_x_mozilla_cycle_collect,257
02:55:41 INFO - PID 28826 | __end_cc_report
02:55:41 INFO - PID 28826 | __startTimestamp1511866541027__endTimestamp
02:55:41 INFO - PID 28826 |
02:55:41 INFO - PID 28826 | ------- Summary: start -------
02:55:41 INFO - PID 28826 | Number of tests: 1
02:55:41 INFO - PID 28826 |
02:55:41 INFO - PID 28826 | [#0] /displaylist_mutate.html Cycles:5 Average:6061.05 Median:6124.58 stddev:118.50 (1.9%) stddev-sans-first:64.44
02:55:41 INFO - PID 28826 | Values: 5874.1 6012.7 6152.8 6124.6 6141.1
02:55:41 INFO - PID 28826 | -------- Summary: end --------
02:55:41 INFO - PID 28826 |
02:55:41 INFO - PID 28826 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
02:55:46 INFO - TEST-INFO | 28826: exit 0
02:55:46 INFO - TEST-OK | displaylist_mutate | took 53798ms
02:55:46 INFO - SUITE-END | took 654s
02:55:46 INFO - Completed test suite (00:10:54)
02:55:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.570697501170729, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.6525773651635722, 2.609591304347826, 2.6073805386620332, 2.623291083916084, 2.6066203301476976, 2.6180715532286216, 2.6047569444444445, 2.606863596872285, 2.6156408020924147, 2.6229283216783217, 2.6067072111207645, 2.6117362924281986], "value": 2.609591304347826, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.591029792746114, 2.597623376623376, 2.595097276264591, 2.6062955729166664, 2.5947211413748383, 2.5987597402597395, 2.6046875000000003, 2.601345903771131, 2.59837012987013, 2.598025974025974, 2.5982077922077926, 2.601762028608582], "value": 2.59837012987013, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.657488928255093, 2.6600797872340425, 2.657462356067316, 2.6478331862312445, 2.648089143865843, 2.6457098765432105, 2.655, 2.652727674624226, 2.648702559576346, 2.6510468197879864, 2.648186231244484, 2.6484466019417474], "value": 2.648702559576346, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.635797101449275, 2.649019867549668, 2.642331571994717, 2.642483487450462, 2.650304635761589, 2.6384696569920845, 2.642490092470277, 2.646335978835979, 2.6458465608465622, 2.6393733509234836, 2.638806068601582, 2.6392744063324547], "value": 2.642483487450462, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.2263440860215056, 3.2263010752688164, 3.226548387096775, 3.2232599355531684, 3.237092772384034, 3.2401565874730025, 3.21994635193133, 3.2261666666666677, 3.2189270386266102, 3.2226960257787325, 3.237195253505932, 3.22690322580645], "value": 3.2263010752688164, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.2154099678456602, 3.2168890675241166, 3.2158118971061103, 3.215924437299036, 3.216374598070741, 3.2116452648475113, 3.2160852090032166, 3.220724637681159, 3.215844051446944, 3.210433386837882, 3.2271935483870977, 3.2054727564102565], "value": 3.215924437299036, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.305107604017214, 4.279950071326677, 4.3603633720930235, 4.348688405797101, 4.566248097412481, 4.27969329529244, 4.273368945868947, 4.673208722741431, 4.601157975460122, 4.665816485225506, 4.273611111111112, 4.594532924961717], "value": 4.3603633720930235, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.219957805907177, 4.256648936170213, 4.301548387096776, 4.311756465517247, 4.610241935483874, 4.238506355932205, 4.202310924369747, 4.641856148491886, 4.486165919282515, 4.652593023255809, 4.247292993630571, 4.631655092592593], "value": 4.311756465517247, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.36836244541485, 4.304540889526542, 4.373352769679299, 4.566156773211568, 4.672928348909658, 4.445925925925926, 4.511398496240604, 4.249695467422096, 4.498995502248873, 4.437892011834319, 4.292238912732472, 4.317899280575538], "value": 4.437892011834319, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.406013215859026, 4.183995815899583, 4.3584313725490205, 4.5675913242009125, 4.526459276018099, 4.425099557522125, 4.348032608695647, 4.158596673596674, 4.48511210762332, 4.331060606060608, 4.229693446088796, 4.167135416666671], "value": 4.348032608695647, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.8870032573289945, 4.8637601296596475, 4.847600969305333, 4.966812913907288, 4.8471809369951515, 4.855250809061491, 4.8470032310177675, 4.816653290529698, 4.855792880258901, 4.838959677419353, 4.840241935483872, 4.855242718446603], "value": 4.847600969305333, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.854393203883491, 4.809218749999996, 4.786244019138751, 4.868454987834544, 4.785035885167465, 4.820445783132532, 4.819746987951807, 4.775381861575175, 4.785370813397129, 4.807668269230773, 4.832379227053139, 4.809843750000001], "value": 4.809218749999996, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.324914634146342, 7.35332107843137, 7.335831295843529, 7.371756756756757, 7.371597051597056, 7.317463414634155, 7.371400491400491, 7.319256097560976, 7.33544009779951, 7.353517156862756, 7.390849753694575, 7.3195609756097575], "value": 7.35332107843137, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.0937765957446794, 7.119412811387901, 7.0678975265017545, 7.168584229390667, 7.11927046263345, 7.069929328621885, 7.171648745519711, 7.0937943262411505, 7.067738515901054, 7.11953736654803, 7.095425531914906, 7.118594306049812], "value": 7.118594306049812, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.336356968215158, 7.3353300733496365, 7.335036674816623, 7.389679802955663, 7.3017153284671465, 7.354558823529421, 7.317768292682936, 7.317780487804862, 7.284101941747562, 7.299464720194634, 7.336283618581909, 7.301654501216553], "value": 7.317780487804862, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.119572953736675, 7.120747330960855, 7.1194483985765205, 7.168279569892463, 7.119412811387901, 7.067773851590081, 7.121032028469732, 7.1176156583629995, 7.06765017667846, 7.070830388692599, 7.119181494661914, 7.121067615658352], "value": 7.119412811387901, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.390024630541868, 7.282439320388344, 7.323012195121946, 7.283822815533993, 7.354705882352944, 7.390147783251235, 7.318646341463407, 7.3010948905109485, 7.372235872235872, 7.3357946210269045, 7.353517156862747, 7.317121951219504], "value": 7.323012195121946, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.144214285714303, 7.092677304964546, 7.143392857142862, 7.094485815602826, 7.119306049822069, 7.095656028368789, 7.0789045936395825, 7.0927659574468205, 7.069063604240276, 7.093085106382978, 7.120943060498223, 7.067402826855139], "value": 7.093085106382978, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.9097, 6.50885, 6.567950000000001, 6.6159, 6.65035], "value": 6.591925, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5874.055, 6012.72, 6152.845, 6124.575000000001, 6141.0650000000005], "value": 6132.820000000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
02:55:47 INFO - Return code: 0
02:55:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
02:55:47 INFO - # TBPL SUCCESS #
02:55:47 INFO - Running post-action listener: _package_coverage_data
02:55:47 INFO - Running post-action listener: _resource_record_post_action
02:55:47 INFO - [mozharness: 2017-11-28 10:55:47.141404Z] Finished run-tests step (success)
02:55:47 INFO - Running post-run listener: _resource_record_post_run
02:55:47 INFO - Total resource usage - Wall time: 674s; CPU: 43.0%; Read bytes: 25800704; Write bytes: 350916608; Read time: 11176; Write time: 272836
02:55:47 INFO - TinderboxPrint: CPU usage
43.4%
02:55:47 INFO - TinderboxPrint: I/O read bytes / time
25,800,704 / 11,176
02:55:47 INFO - TinderboxPrint: I/O write bytes / time
350,916,608 / 272,836
02:55:47 INFO - TinderboxPrint: CPU idle
2,853.4 (56.3%)
02:55:47 INFO - TinderboxPrint: CPU system
662.0 (13.1%)
02:55:47 INFO - TinderboxPrint: CPU user
1,521.6 (30.0%)
02:55:47 INFO - TinderboxPrint: Swap in / out
0 / 0
02:55:47 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 6676480; Read time: 0; Write time: 944
02:55:47 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
02:55:47 INFO - run-tests - Wall time: 655s; CPU: 44.0%; Read bytes: 24580096; Write bytes: 344240128; Read time: 10452; Write time: 271892
02:55:47 INFO - Running post-run listener: _upload_blobber_files
02:55:47 INFO - Blob upload gear active.
02:55:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
02:55:47 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
02:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
02:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
02:55:47 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
02:55:47 INFO - SNIMissingWarning
02:55:47 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
02:55:47 INFO - InsecurePlatformWarning
02:55:47 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.)
02:55:47 INFO - SubjectAltNameWarning
02:55:47 INFO - (blobuploader) - INFO - Open directory for files ...
02:55:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
02:55:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:55:47 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:55:48 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
02:55:48 INFO - InsecurePlatformWarning
02:55:48 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.)
02:55:48 INFO - SubjectAltNameWarning
02:55:53 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
02:55:53 INFO - InsecurePlatformWarning
02:55:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
02:55:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:55:53 INFO - (blobuploader) - INFO - Done attempting.
02:55:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
02:55:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:55:53 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:55:53 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
02:55:53 INFO - InsecurePlatformWarning
02:55:53 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.)
02:55:53 INFO - SubjectAltNameWarning
02:55:54 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
02:55:54 INFO - InsecurePlatformWarning
02:55:54 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
02:55:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:55:54 INFO - (blobuploader) - INFO - Done attempting.
02:55:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
02:55:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:55:54 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:55:54 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
02:55:54 INFO - InsecurePlatformWarning
02:55:54 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.)
02:55:54 INFO - SubjectAltNameWarning
02:55:54 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
02:55:54 INFO - InsecurePlatformWarning
02:55:55 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
02:55:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:55:55 INFO - (blobuploader) - INFO - Done attempting.
02:55:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
02:55:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
02:55:55 INFO - (blobuploader) - INFO - Uploading, attempt #1.
02:55:55 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
02:55:55 INFO - InsecurePlatformWarning
02:55:55 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.)
02:55:55 INFO - SubjectAltNameWarning
02:55:55 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
02:55:55 INFO - InsecurePlatformWarning
02:55:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
02:55:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
02:55:55 INFO - (blobuploader) - INFO - Done attempting.
02:55:55 INFO - (blobuploader) - INFO - Iteration through files over.
02:55:55 INFO - Return code: 0
02:55:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
02:55:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
02:55:55 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c0a09487c8cdb16ca8b8654a5a72cedce8a11c405a941557319b0260682dbec0b34813b2582ec698ebe9079edb84536e2baeafd98bb952408b70809af0deaeb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f52db242007e6183d8916750ff3982ac7ec7782c12c5d37df23e4615a7ab02fd4f38cd99fd664b78c136e568562f0a0114012054cac91a37601997923826b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7732e6b8bd667c22d926aab61281f674f56aab026be5a01eb529c5819bf3528fa96854acc0a921d9c979ea4ee589ae3e0f1d78db4ee0932a99def3ac859650ec", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fb5250cef318314a73fbb3b0618b68389fb91fe48f301fe6ad1abd577e4782504468ea333feffbe3d11a920cdd8445e35009ae1430ae0de80935389031e324"}
02:55:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
02:55:55 INFO - Writing to file /builds/slave/test/properties/blobber_files
02:55:55 INFO - Contents:
02:55:55 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c0a09487c8cdb16ca8b8654a5a72cedce8a11c405a941557319b0260682dbec0b34813b2582ec698ebe9079edb84536e2baeafd98bb952408b70809af0deaeb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f52db242007e6183d8916750ff3982ac7ec7782c12c5d37df23e4615a7ab02fd4f38cd99fd664b78c136e568562f0a0114012054cac91a37601997923826b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7732e6b8bd667c22d926aab61281f674f56aab026be5a01eb529c5819bf3528fa96854acc0a921d9c979ea4ee589ae3e0f1d78db4ee0932a99def3ac859650ec", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fb5250cef318314a73fbb3b0618b68389fb91fe48f301fe6ad1abd577e4782504468ea333feffbe3d11a920cdd8445e35009ae1430ae0de80935389031e324"}
02:55:55 INFO - Running post-run listener: copy_logs_to_upload_dir
02:55:55 INFO - Copying logs to upload dir...
02:55:55 INFO - mkdir: /builds/slave/test/build/upload/logs
02:55:55 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=740.590870
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 20 secs) (at 2017-11-28 02:55:56.004815) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-28 02:55:56.008143) =========
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=52faae308a556c440401f8e5000001e8-1511865670.455565-785011975
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c0a09487c8cdb16ca8b8654a5a72cedce8a11c405a941557319b0260682dbec0b34813b2582ec698ebe9079edb84536e2baeafd98bb952408b70809af0deaeb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f52db242007e6183d8916750ff3982ac7ec7782c12c5d37df23e4615a7ab02fd4f38cd99fd664b78c136e568562f0a0114012054cac91a37601997923826b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7732e6b8bd667c22d926aab61281f674f56aab026be5a01eb529c5819bf3528fa96854acc0a921d9c979ea4ee589ae3e0f1d78db4ee0932a99def3ac859650ec", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fb5250cef318314a73fbb3b0618b68389fb91fe48f301fe6ad1abd577e4782504468ea333feffbe3d11a920cdd8445e35009ae1430ae0de80935389031e324"}
build_url:https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011570
build_url: 'https://queue.taskcluster.net/v1/task/SoycHxfBTgixI_jqAvC36w/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8c0a09487c8cdb16ca8b8654a5a72cedce8a11c405a941557319b0260682dbec0b34813b2582ec698ebe9079edb84536e2baeafd98bb952408b70809af0deaeb", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/944f52db242007e6183d8916750ff3982ac7ec7782c12c5d37df23e4615a7ab02fd4f38cd99fd664b78c136e568562f0a0114012054cac91a37601997923826b", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7732e6b8bd667c22d926aab61281f674f56aab026be5a01eb529c5819bf3528fa96854acc0a921d9c979ea4ee589ae3e0f1d78db4ee0932a99def3ac859650ec", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b5fb5250cef318314a73fbb3b0618b68389fb91fe48f301fe6ad1abd577e4782504468ea333feffbe3d11a920cdd8445e35009ae1430ae0de80935389031e324"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-28 02:55:56.039339) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:55:56.039733) =========
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=52faae308a556c440401f8e5000001e8-1511865670.455565-785011975
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004521
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-28 02:55:56.124994) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-28 02:55:56.125356) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-28 02:55:56.125706) =========
========= Total master_lag: 0.21 =========