builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-011
starttime: 1511648317.11
results: success (0)
revision: f045ac9f76cfb985032050f74b9d9ba20c216e32
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.114105) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.114531) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.114816) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.133686) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.133984) =========
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=5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-25 14:18:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 64.0K=0.2s
2017-11-25 14:18:37 (64.0 KB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.279115
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.431306) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.431641) =========
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=5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.015889
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:37.482330) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-25 14:18:37.482720) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f045ac9f76cfb985032050f74b9d9ba20c216e32 --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 f045ac9f76cfb985032050f74b9d9ba20c216e32 --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=5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-25 14:18:37,534 truncating revision to first 12 chars
2017-11-25 14:18:37,534 Setting DEBUG logging.
2017-11-25 14:18:37,534 attempt 1/10
2017-11-25 14:18:37,534 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f045ac9f76cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-25 14:18:38,737 unpacking tar archive at: mozilla-central-f045ac9f76cf/testing/mozharness/
program finished with exit code 0
elapsedTime=1.918186
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-25 14:18:39.418579) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:39.418910) =========
script_repo_revision: f045ac9f76cfb985032050f74b9d9ba20c216e32
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:39.419281) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:39.419540) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-25 14:18:39.515170) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 6 secs) (at 2017-11-25 14:18:39.515546) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
14:18:39 INFO - MultiFileLogger online at 20171125 14:18:39 in /builds/slave/test
14:18:39 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
14:18:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
14:18:39 INFO - {'append_to_log': False,
14:18:39 INFO - 'base_work_dir': '/builds/slave/test',
14:18:39 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
14:18:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
14:18:39 INFO - 'branch': 'Firefox-Non-PGO',
14:18:39 INFO - 'buildbot_json_path': 'buildprops.json',
14:18:39 INFO - 'code_coverage': False,
14:18:39 INFO - 'config_files': ('talos/linux_config.py',),
14:18:39 INFO - 'default_actions': ('clobber',
14:18:39 INFO - 'read-buildbot-config',
14:18:39 INFO - 'download-and-extract',
14:18:39 INFO - 'populate-webroot',
14:18:39 INFO - 'create-virtualenv',
14:18:39 INFO - 'install',
14:18:39 INFO - 'setup-mitmproxy',
14:18:39 INFO - 'run-tests'),
14:18:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
14:18:39 INFO - 'disable_ccov_upload': False,
14:18:39 INFO - 'disable_stylo': False,
14:18:39 INFO - 'download_minidump_stackwalk': True,
14:18:39 INFO - 'download_symbols': 'ondemand',
14:18:39 INFO - 'e10s': False,
14:18:39 INFO - 'enable_stylo': False,
14:18:39 INFO - 'enable_webrender': False,
14:18:39 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
14:18:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
14:18:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
14:18:39 INFO - 'gecko_profile': False,
14:18:39 INFO - 'gecko_profile_interval': 0,
14:18:39 INFO - 'installer_path': 'installer.exe',
14:18:39 INFO - 'jsd_code_coverage': False,
14:18:39 INFO - 'log_level': 'info',
14:18:39 INFO - 'log_name': 'talos',
14:18:39 INFO - 'log_to_console': True,
14:18:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
14:18:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
14:18:39 INFO - 'opt_config_files': (),
14:18:39 INFO - 'pip_index': False,
14:18:39 INFO - 'suite': 'g4-e10s',
14:18:39 INFO - 'system_bits': '32',
14:18:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
14:18:39 INFO - 'title': 'talos-linux64-ix-011',
14:18:39 INFO - 'tooltool_cache': '/builds/tooltool_cache',
14:18:39 INFO - 'use_talos_json': True,
14:18:39 INFO - 'verify': 'False',
14:18:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
14:18:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
14:18:39 INFO - 'work_dir': 'build'}
14:18:39 INFO - [mozharness: 2017-11-25 22:18:39.700717Z] Running clobber step.
14:18:39 INFO - Running pre-action listener: _resource_record_pre_action
14:18:39 INFO - Running main action method: clobber
14:18:39 INFO - rmtree: /builds/slave/test/build
14:18:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
14:18:40 INFO - Running post-action listener: _resource_record_post_action
14:18:40 INFO - [mozharness: 2017-11-25 22:18:40.791455Z] Finished clobber step (success)
14:18:40 INFO - [mozharness: 2017-11-25 22:18:40.791551Z] Running read-buildbot-config step.
14:18:40 INFO - Running pre-action listener: _resource_record_pre_action
14:18:40 INFO - Running main action method: read_buildbot_config
14:18:40 INFO - Using buildbot properties:
14:18:40 INFO - {
14:18:40 INFO - "project": "",
14:18:40 INFO - "product": "firefox",
14:18:40 INFO - "who": "cbrindusan@mozilla.com",
14:18:40 INFO - "installer_path": "public/build/target.tar.bz2",
14:18:40 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
14:18:40 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
14:18:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
14:18:40 INFO - "stage_platform": "linux64",
14:18:40 INFO - "basedir": "/builds/slave/test",
14:18:40 INFO - "buildnumber": 870,
14:18:40 INFO - "platform": "ubuntu64_hw",
14:18:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
14:18:40 INFO - "slavebuilddir": "test",
14:18:40 INFO - "taskId": "fCcihFoMS0mlMBf7bGTuWA",
14:18:40 INFO - "branch": "mozilla-central",
14:18:40 INFO - "script_repo_revision": "production",
14:18:40 INFO - "revision": "f045ac9f76cfb985032050f74b9d9ba20c216e32",
14:18:40 INFO - "slavename": "talos-linux64-ix-011",
14:18:40 INFO - "repo_path": "mozilla-central"
14:18:40 INFO - }
14:18:40 INFO - Finding installer, test and symbols from parent task.
14:18:40 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fCcihFoMS0mlMBf7bGTuWA'}, attempt #1
14:18:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fCcihFoMS0mlMBf7bGTuWA'}, attempt #1
14:18:41 INFO - Task dependencies: fy9Hgj18RxSDrfWVwwbQpQ
14:18:41 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ'}, attempt #1
14:18:42 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2
14:18:42 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.test_packages.json
14:18:42 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.crashreporter-symbols.zip
14:18:42 INFO - Running post-action listener: _resource_record_post_action
14:18:42 INFO - [mozharness: 2017-11-25 22:18:42.135809Z] Finished read-buildbot-config step (success)
14:18:42 INFO - [mozharness: 2017-11-25 22:18:42.136026Z] Running download-and-extract step.
14:18:42 INFO - Running pre-action listener: _resource_record_pre_action
14:18:42 INFO - Running main action method: download_and_extract
14:18:42 INFO - mkdir: /builds/slave/test/build/tests
14:18:42 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')]}
14:18:42 INFO - trying https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.test_packages.json
14:18:42 INFO - Downloading https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
14:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
14:18:42 INFO - Downloaded 989 bytes.
14:18:42 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
14:18:42 INFO - Using the following test package requirements:
14:18:42 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
14:18:42 INFO - u'common': [u'target.common.tests.zip'],
14:18:42 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
14:18:42 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
14:18:42 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
14:18:42 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
14:18:42 INFO - u'mozbase': [u'target.common.tests.zip'],
14:18:42 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
14:18:42 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
14:18:42 INFO - u'web-platform': [u'target.common.tests.zip',
14:18:42 INFO - u'target.web-platform.tests.tar.gz'],
14:18:42 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
14:18:42 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
14:18:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.common.tests.zip
14:18:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.common.tests.zip'}, attempt #1
14:18:42 INFO - Fetch https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.common.tests.zip into memory
14:18:43 INFO - Content-Length response header: 59113032
14:18:43 INFO - Bytes received: 59113032
14:18:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.talos.tests.zip
14:18:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1
14:18:55 INFO - Fetch https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.talos.tests.zip into memory
14:18:55 INFO - Content-Length response header: 18744811
14:18:55 INFO - Bytes received: 18744811
14:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:18:56 INFO - trying https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2
14:18:56 INFO - Downloading https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
14:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
14:18:57 INFO - Downloaded 62601670 bytes.
14:18:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2
14:18:57 INFO - mkdir: /builds/slave/test/properties
14:18:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
14:18:57 INFO - Writing to file /builds/slave/test/properties/build_url
14:18:57 INFO - Contents:
14:18:57 INFO - build_url:https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2
14:18:57 INFO - Running post-action listener: _resource_record_post_action
14:18:57 INFO - Running post-action listener: find_tests_for_verification
14:18:57 INFO - Running post-action listener: set_extra_try_arguments
14:18:57 INFO - [mozharness: 2017-11-25 22:18:57.471026Z] Finished download-and-extract step (success)
14:18:57 INFO - [mozharness: 2017-11-25 22:18:57.471166Z] Running populate-webroot step.
14:18:57 INFO - Running pre-action listener: _resource_record_pre_action
14:18:57 INFO - Running main action method: populate_webroot
14:18:57 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
14:18:57 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['tresize',
14:18:57 INFO - 'tcanvasmark']},
14:18:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
14:18:57 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['dromaeo_css',
14:18:57 INFO - 'kraken']},
14:18:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
14:18:57 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
14:18:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'tests': ['damp', 'tps']},
14:18:57 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['damp', 'tps']},
14:18:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
14:18:57 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['dromaeo_dom']},
14:18:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
14:18:57 INFO - 'glvideo',
14:18:57 INFO - 'displaylist_mutate']},
14:18:57 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['basic_compositor_video',
14:18:57 INFO - 'glvideo']},
14:18:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
14:18:57 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['ts_paint_webext',
14:18:57 INFO - 'tp5o_webext']},
14:18:57 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
14:18:57 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
14:18:57 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
14:18:57 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
14:18:57 INFO - 'talos_options': ['--mitmproxy',
14:18:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
14:18:57 INFO - '--firstNonBlankPaint'],
14:18:57 INFO - 'tests': ['tp6_google_heavy',
14:18:57 INFO - 'tp6_youtube_heavy',
14:18:57 INFO - 'tp6_amazon_heavy',
14:18:57 INFO - 'tp6_facebook_heavy']},
14:18:57 INFO - 'other-e10s': {'tests': ['a11yr',
14:18:57 INFO - 'ts_paint',
14:18:57 INFO - 'tpaint',
14:18:57 INFO - 'sessionrestore',
14:18:57 INFO - 'sessionrestore_many_windows',
14:18:57 INFO - 'sessionrestore_no_auto_restore',
14:18:57 INFO - 'tabpaint',
14:18:57 INFO - 'cpstartup']},
14:18:57 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['a11yr',
14:18:57 INFO - 'ts_paint',
14:18:57 INFO - 'tpaint',
14:18:57 INFO - 'sessionrestore',
14:18:57 INFO - 'sessionrestore_many_windows',
14:18:57 INFO - 'sessionrestore_no_auto_restore',
14:18:57 INFO - 'tabpaint',
14:18:57 INFO - 'cpstartup']},
14:18:57 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
14:18:57 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
14:18:57 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['perf_reftest_singletons']},
14:18:57 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['perf_reftest']},
14:18:57 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
14:18:57 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['speedometer']},
14:18:57 INFO - 'svgr-e10s': {'tests': ['tsvgx',
14:18:57 INFO - 'tsvgr_opacity',
14:18:57 INFO - 'tart',
14:18:57 INFO - 'tscrollx',
14:18:57 INFO - 'tsvg_static']},
14:18:57 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['tsvgx',
14:18:57 INFO - 'tsvgr_opacity',
14:18:57 INFO - 'tart',
14:18:57 INFO - 'tscrollx',
14:18:57 INFO - 'tsvg_static']},
14:18:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
14:18:57 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'talos_options': ['--disable-stylo'],
14:18:57 INFO - 'tests': ['tp5o']},
14:18:57 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
14:18:57 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
14:18:57 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
14:18:57 INFO - 'talos_options': ['--mitmproxy',
14:18:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
14:18:57 INFO - '--firstNonBlankPaint'],
14:18:57 INFO - 'tests': ['tp6_google',
14:18:57 INFO - 'tp6_youtube',
14:18:57 INFO - 'tp6_amazon',
14:18:57 INFO - 'tp6_facebook']},
14:18:57 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
14:18:57 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
14:18:57 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
14:18:57 INFO - 'talos_options': ['--disable-stylo',
14:18:57 INFO - '--mitmproxy',
14:18:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
14:18:57 INFO - '--firstNonBlankPaint'],
14:18:57 INFO - 'tests': ['tp6_google',
14:18:57 INFO - 'tp6_youtube',
14:18:57 INFO - 'tp6_amazon',
14:18:57 INFO - 'tp6_facebook']},
14:18:57 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
14:18:57 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
14:18:57 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
14:18:57 INFO - 'talos_options': ['--stylo-threads=1',
14:18:57 INFO - '--mitmproxy',
14:18:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
14:18:57 INFO - '--firstNonBlankPaint'],
14:18:57 INFO - 'tests': ['tp6_google',
14:18:57 INFO - 'tp6_youtube',
14:18:57 INFO - 'tp6_amazon',
14:18:57 INFO - 'tp6_facebook']},
14:18:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'talos_options': ['--xperf_path',
14:18:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:18:57 INFO - 'tests': ['tp5n']},
14:18:57 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
14:18:57 INFO - 'talos_options': ['--disable-stylo',
14:18:57 INFO - '--xperf_path',
14:18:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
14:18:57 INFO - 'tests': ['tp5n']}}}
14:18:57 INFO - Running post-action listener: _resource_record_post_action
14:18:57 INFO - [mozharness: 2017-11-25 22:18:57.491384Z] Finished populate-webroot step (success)
14:18:57 INFO - [mozharness: 2017-11-25 22:18:57.491497Z] Running create-virtualenv step.
14:18:57 INFO - Running pre-action listener: _resource_record_pre_action
14:18:57 INFO - Running main action method: create_virtualenv
14:18:57 INFO - Creating virtualenv /builds/slave/test/build/venv
14:18:57 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
14:18:57 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
14:18:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
14:18:57 INFO - Using real prefix '/usr'
14:18:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python
14:18:57 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
14:19:00 INFO - Installing setuptools, pip, wheel...done.
14:19:00 INFO - Return code: 0
14:19:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
14:19: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')]}
14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19: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')]}
14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19: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', 'pip>=1.5'] in /builds/slave/test/build
14:19: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 pip>=1.5
14:19:00 INFO - Using env: {'DISPLAY': ':0',
14:19:00 INFO - 'HOME': '/home/cltbld',
14:19:00 INFO - 'LANG': 'en_US.UTF-8',
14:19:00 INFO - 'LANGUAGE': 'en_US:en',
14:19:00 INFO - 'LOGNAME': 'cltbld',
14:19:00 INFO - 'MAIL': '/var/mail/cltbld',
14:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:00 INFO - 'MOZ_NO_REMOTE': '1',
14:19:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:00 INFO - 'NO_EM_RESTART': '1',
14:19:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:00 INFO - 'PWD': '/builds/slave/test',
14:19:00 INFO - 'SHELL': '/bin/bash',
14:19:00 INFO - 'SHLVL': '1',
14:19:00 INFO - 'TERM': 'linux',
14:19:00 INFO - 'TMOUT': '86400',
14:19:00 INFO - 'USER': 'cltbld',
14:19:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:00 INFO - '_': '/tools/buildbot/bin/python'}
14:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
14:19:01 INFO - Return code: 0
14:19:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
14:19: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')]}
14:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19: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')]}
14:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19: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', '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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19: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', 'psutil>=3.1.1'] in /builds/slave/test/build
14:19: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 psutil>=3.1.1
14:19:01 INFO - Using env: {'DISPLAY': ':0',
14:19:01 INFO - 'HOME': '/home/cltbld',
14:19:01 INFO - 'LANG': 'en_US.UTF-8',
14:19:01 INFO - 'LANGUAGE': 'en_US:en',
14:19:01 INFO - 'LOGNAME': 'cltbld',
14:19:01 INFO - 'MAIL': '/var/mail/cltbld',
14:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:01 INFO - 'MOZ_NO_REMOTE': '1',
14:19:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:01 INFO - 'NO_EM_RESTART': '1',
14:19:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:01 INFO - 'PWD': '/builds/slave/test',
14:19:01 INFO - 'SHELL': '/bin/bash',
14:19:01 INFO - 'SHLVL': '1',
14:19:01 INFO - 'TERM': 'linux',
14:19:01 INFO - 'TMOUT': '86400',
14:19:01 INFO - 'USER': 'cltbld',
14:19:01 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:01 INFO - '_': '/tools/buildbot/bin/python'}
14:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:01 INFO - Collecting psutil>=3.1.1
14:19:02 INFO - Installing collected packages: psutil
14:19:02 INFO - Successfully installed psutil-3.1.1
14:19:02 INFO - Return code: 0
14:19:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
14:19:02 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')]}
14:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19:02 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')]}
14:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19:02 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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19:02 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
14:19:02 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
14:19:02 INFO - Using env: {'DISPLAY': ':0',
14:19:02 INFO - 'HOME': '/home/cltbld',
14:19:02 INFO - 'LANG': 'en_US.UTF-8',
14:19:02 INFO - 'LANGUAGE': 'en_US:en',
14:19:02 INFO - 'LOGNAME': 'cltbld',
14:19:02 INFO - 'MAIL': '/var/mail/cltbld',
14:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:02 INFO - 'MOZ_NO_REMOTE': '1',
14:19:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:02 INFO - 'NO_EM_RESTART': '1',
14:19:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:02 INFO - 'PWD': '/builds/slave/test',
14:19:02 INFO - 'SHELL': '/bin/bash',
14:19:02 INFO - 'SHLVL': '1',
14:19:02 INFO - 'TERM': 'linux',
14:19:02 INFO - 'TMOUT': '86400',
14:19:02 INFO - 'USER': 'cltbld',
14:19:02 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:02 INFO - '_': '/tools/buildbot/bin/python'}
14:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:03 INFO - Collecting mozsystemmonitor==0.3
14:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
14:19:04 INFO - Installing collected packages: mozsystemmonitor
14:19:05 INFO - Successfully installed mozsystemmonitor-0.3
14:19:05 INFO - Return code: 0
14:19:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
14:19: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')]}
14:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19: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')]}
14:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19: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', '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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19: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', 'jsonschema==2.5.1'] in /builds/slave/test/build
14:19: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 jsonschema==2.5.1
14:19:05 INFO - Using env: {'DISPLAY': ':0',
14:19:05 INFO - 'HOME': '/home/cltbld',
14:19:05 INFO - 'LANG': 'en_US.UTF-8',
14:19:05 INFO - 'LANGUAGE': 'en_US:en',
14:19:05 INFO - 'LOGNAME': 'cltbld',
14:19:05 INFO - 'MAIL': '/var/mail/cltbld',
14:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:05 INFO - 'MOZ_NO_REMOTE': '1',
14:19:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:05 INFO - 'NO_EM_RESTART': '1',
14:19:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:05 INFO - 'PWD': '/builds/slave/test',
14:19:05 INFO - 'SHELL': '/bin/bash',
14:19:05 INFO - 'SHLVL': '1',
14:19:05 INFO - 'TERM': 'linux',
14:19:05 INFO - 'TMOUT': '86400',
14:19:05 INFO - 'USER': 'cltbld',
14:19:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:05 INFO - '_': '/tools/buildbot/bin/python'}
14:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:05 INFO - Collecting jsonschema==2.5.1
14:19:07 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
14:19:07 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
14:19:08 INFO - Installing collected packages: functools32, jsonschema
14:19:08 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
14:19:08 INFO - Return code: 0
14:19:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
14:19:08 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')]}
14:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19:08 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')]}
14:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19:08 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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19:08 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
14:19:08 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
14:19:08 INFO - Using env: {'DISPLAY': ':0',
14:19:08 INFO - 'HOME': '/home/cltbld',
14:19:08 INFO - 'LANG': 'en_US.UTF-8',
14:19:08 INFO - 'LANGUAGE': 'en_US:en',
14:19:08 INFO - 'LOGNAME': 'cltbld',
14:19:08 INFO - 'MAIL': '/var/mail/cltbld',
14:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:08 INFO - 'MOZ_NO_REMOTE': '1',
14:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:08 INFO - 'NO_EM_RESTART': '1',
14:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:08 INFO - 'PWD': '/builds/slave/test',
14:19:08 INFO - 'SHELL': '/bin/bash',
14:19:08 INFO - 'SHLVL': '1',
14:19:08 INFO - 'TERM': 'linux',
14:19:08 INFO - 'TMOUT': '86400',
14:19:08 INFO - 'USER': 'cltbld',
14:19:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:08 INFO - '_': '/tools/buildbot/bin/python'}
14:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
14:19:09 INFO - Return code: 0
14:19:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
14:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19:09 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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19:09 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
14:19:09 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
14:19:09 INFO - Using env: {'DISPLAY': ':0',
14:19:09 INFO - 'HOME': '/home/cltbld',
14:19:09 INFO - 'LANG': 'en_US.UTF-8',
14:19:09 INFO - 'LANGUAGE': 'en_US:en',
14:19:09 INFO - 'LOGNAME': 'cltbld',
14:19:09 INFO - 'MAIL': '/var/mail/cltbld',
14:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:09 INFO - 'MOZ_NO_REMOTE': '1',
14:19:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:09 INFO - 'NO_EM_RESTART': '1',
14:19:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:09 INFO - 'PWD': '/builds/slave/test',
14:19:09 INFO - 'SHELL': '/bin/bash',
14:19:09 INFO - 'SHLVL': '1',
14:19:09 INFO - 'TERM': 'linux',
14:19:09 INFO - 'TMOUT': '86400',
14:19:09 INFO - 'USER': 'cltbld',
14:19:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:09 INFO - '_': '/tools/buildbot/bin/python'}
14:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:09 INFO - Collecting blobuploader==1.2.4
14:19:10 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
14:19:11 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
14:19:12 INFO - Installing collected packages: requests, docopt, blobuploader
14:19:12 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
14:19:12 INFO - Return code: 0
14:19:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv
14:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19:12 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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19:12 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
14:19:12 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
14:19:12 INFO - Using env: {'DISPLAY': ':0',
14:19:12 INFO - 'HOME': '/home/cltbld',
14:19:12 INFO - 'LANG': 'en_US.UTF-8',
14:19:12 INFO - 'LANGUAGE': 'en_US:en',
14:19:12 INFO - 'LOGNAME': 'cltbld',
14:19:12 INFO - 'MAIL': '/var/mail/cltbld',
14:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:12 INFO - 'MOZ_NO_REMOTE': '1',
14:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:12 INFO - 'NO_EM_RESTART': '1',
14:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:12 INFO - 'PWD': '/builds/slave/test',
14:19:12 INFO - 'SHELL': '/bin/bash',
14:19:12 INFO - 'SHLVL': '1',
14:19:12 INFO - 'TERM': 'linux',
14:19:12 INFO - 'TMOUT': '86400',
14:19:12 INFO - 'USER': 'cltbld',
14:19:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:12 INFO - '_': '/tools/buildbot/bin/python'}
14:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:13 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
14:19:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
14:19:13 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
14:19:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
14:19:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
14:19:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
14:19:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
14:19:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
14:19:14 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
14:19:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
14:19:15 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
14:19:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
14:19:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
14:19:15 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
14:19:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
14:19:16 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
14:19:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
14:19:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
14:19:16 INFO - Running setup.py install for manifestparser: started
14:19:16 INFO - Running setup.py install for manifestparser: finished with status 'done'
14:19:16 INFO - Running setup.py install for mozcrash: started
14:19:17 INFO - Running setup.py install for mozcrash: finished with status 'done'
14:19:17 INFO - Running setup.py install for mozdebug: started
14:19:17 INFO - Running setup.py install for mozdebug: finished with status 'done'
14:19:17 INFO - Running setup.py install for mozdevice: started
14:19:17 INFO - Running setup.py install for mozdevice: finished with status 'done'
14:19:17 INFO - Running setup.py install for mozfile: started
14:19:17 INFO - Running setup.py install for mozfile: finished with status 'done'
14:19:17 INFO - Running setup.py install for mozhttpd: started
14:19:17 INFO - Running setup.py install for mozhttpd: finished with status 'done'
14:19:17 INFO - Running setup.py install for mozinfo: started
14:19:18 INFO - Running setup.py install for mozinfo: finished with status 'done'
14:19:18 INFO - Running setup.py install for mozInstall: started
14:19:18 INFO - Running setup.py install for mozInstall: finished with status 'done'
14:19:18 INFO - Running setup.py install for mozleak: started
14:19:18 INFO - Running setup.py install for mozleak: finished with status 'done'
14:19:18 INFO - Running setup.py install for mozlog: started
14:19:18 INFO - Running setup.py install for mozlog: finished with status 'done'
14:19:18 INFO - Running setup.py install for moznetwork: started
14:19:19 INFO - Running setup.py install for moznetwork: finished with status 'done'
14:19:19 INFO - Running setup.py install for mozprocess: started
14:19:19 INFO - Running setup.py install for mozprocess: finished with status 'done'
14:19:19 INFO - Running setup.py install for mozprofile: started
14:19:19 INFO - Running setup.py install for mozprofile: finished with status 'done'
14:19:19 INFO - Running setup.py install for mozrunner: started
14:19:19 INFO - Running setup.py install for mozrunner: finished with status 'done'
14:19:19 INFO - Running setup.py install for mozscreenshot: started
14:19:19 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
14:19:19 INFO - Running setup.py install for moztest: started
14:19:20 INFO - Running setup.py install for moztest: finished with status 'done'
14:19:20 INFO - Running setup.py install for mozversion: started
14:19:20 INFO - Running setup.py install for mozversion: finished with status 'done'
14:19:20 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
14:19:20 INFO - Return code: 0
14:19:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv
14:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19:20 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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19:20 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
14:19:20 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
14:19:20 INFO - Using env: {'DISPLAY': ':0',
14:19:20 INFO - 'HOME': '/home/cltbld',
14:19:20 INFO - 'LANG': 'en_US.UTF-8',
14:19:20 INFO - 'LANGUAGE': 'en_US:en',
14:19:20 INFO - 'LOGNAME': 'cltbld',
14:19:20 INFO - 'MAIL': '/var/mail/cltbld',
14:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:20 INFO - 'MOZ_NO_REMOTE': '1',
14:19:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:20 INFO - 'NO_EM_RESTART': '1',
14:19:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:20 INFO - 'PWD': '/builds/slave/test',
14:19:20 INFO - 'SHELL': '/bin/bash',
14:19:20 INFO - 'SHLVL': '1',
14:19:20 INFO - 'TERM': 'linux',
14:19:20 INFO - 'TMOUT': '86400',
14:19:20 INFO - 'USER': 'cltbld',
14:19:20 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:20 INFO - '_': '/tools/buildbot/bin/python'}
14:19:21 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:21 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
14:19:21 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))
14:19:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
14:19:21 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))
14:19:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
14:19:21 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))
14:19:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
14:19:21 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))
14:19:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
14:19:22 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))
14:19:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
14:19:22 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))
14:19:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
14:19:22 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))
14:19:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
14:19:22 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))
14:19:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
14:19:22 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))
14:19:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
14:19:23 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))
14:19:23 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
14:19:23 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))
14:19:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
14:19:23 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))
14:19:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
14:19:23 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))
14:19:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
14:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
14:19:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
14:19:24 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))
14:19:24 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
14:19:24 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))
14:19:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
14:19:24 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))
14:19:24 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
14:19:25 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
14:19:25 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))
14:19:25 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))
14:19:25 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))
14:19:25 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))
14:19:25 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))
14:19:25 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))
14:19:25 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
14:19:26 INFO - Installing collected packages: six, blessings
14:19:26 INFO - Successfully installed blessings-1.6 six-1.10.0
14:19:26 INFO - Return code: 0
14:19:26 INFO - Done creating virtualenv /builds/slave/test/build/venv.
14:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
14:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
14:19:27 INFO - Reading from file tmpfile_stdout
14:19:27 INFO - Reading from file tmpfile_stderr
14:19:27 INFO - Current package versions:
14:19:27 INFO - blessings == 1.6
14:19:27 INFO - blobuploader == 1.2.4
14:19:27 INFO - docopt == 0.6.1
14:19:27 INFO - functools32 == 3.2.3.post2
14:19:27 INFO - jsonschema == 2.5.1
14:19:27 INFO - manifestparser == 1.2
14:19:27 INFO - mozInstall == 1.14
14:19:27 INFO - mozcrash == 1.0
14:19:27 INFO - mozdebug == 0.1
14:19:27 INFO - mozdevice == 0.51
14:19:27 INFO - mozfile == 1.2
14:19:27 INFO - mozhttpd == 0.7
14:19:27 INFO - mozinfo == 0.10
14:19:27 INFO - mozleak == 0.1
14:19:27 INFO - mozlog == 3.5
14:19:27 INFO - moznetwork == 0.27
14:19:27 INFO - mozprocess == 0.25
14:19:27 INFO - mozprofile == 0.29
14:19:27 INFO - mozrunner == 6.13
14:19:27 INFO - mozscreenshot == 0.1
14:19:27 INFO - mozsystemmonitor == 0.3
14:19:27 INFO - moztest == 0.8
14:19:27 INFO - mozversion == 1.4
14:19:27 INFO - psutil == 3.1.1
14:19:27 INFO - requests == 1.2.3
14:19:27 INFO - six == 1.10.0
14:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv
14:19:27 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')]}
14:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19:27 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')]}
14:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19:27 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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19:27 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
14:19:27 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
14:19:27 INFO - Using env: {'DISPLAY': ':0',
14:19:27 INFO - 'HOME': '/home/cltbld',
14:19:27 INFO - 'LANG': 'en_US.UTF-8',
14:19:27 INFO - 'LANGUAGE': 'en_US:en',
14:19:27 INFO - 'LOGNAME': 'cltbld',
14:19:27 INFO - 'MAIL': '/var/mail/cltbld',
14:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:27 INFO - 'MOZ_NO_REMOTE': '1',
14:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:27 INFO - 'NO_EM_RESTART': '1',
14:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:27 INFO - 'PWD': '/builds/slave/test',
14:19:27 INFO - 'SHELL': '/bin/bash',
14:19:27 INFO - 'SHLVL': '1',
14:19:27 INFO - 'TERM': 'linux',
14:19:27 INFO - 'TMOUT': '86400',
14:19:27 INFO - 'USER': 'cltbld',
14:19:27 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:27 INFO - '_': '/tools/buildbot/bin/python'}
14:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:27 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))
14:19:27 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))
14:19:27 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))
14:19:27 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))
14:19:27 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))
14:19:27 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))
14:19:27 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))
14:19:27 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))
14:19:27 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))
14:19:27 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
14:19:28 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
14:19:29 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
14:19:29 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))
14:19:29 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))
14:19:29 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))
14:19:29 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))
14:19:30 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
14:19:30 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
14:19:31 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
14:19:31 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
14:19:33 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
14:19:33 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
14:19:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
14:19:34 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
14:19:34 INFO - Found existing installation: requests 1.2.3
14:19:34 INFO - Uninstalling requests-1.2.3:
14:19:34 INFO - Successfully uninstalled requests-1.2.3
14:19:34 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
14:19:34 INFO - Return code: 0
14:19:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
14:19:34 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')]}
14:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
14:19:34 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')]}
14:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
14:19:34 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 0x1cb2d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c91a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e35460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, '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': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336', '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
14:19:34 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
14:19:34 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
14:19:34 INFO - Using env: {'DISPLAY': ':0',
14:19:34 INFO - 'HOME': '/home/cltbld',
14:19:34 INFO - 'LANG': 'en_US.UTF-8',
14:19:34 INFO - 'LANGUAGE': 'en_US:en',
14:19:34 INFO - 'LOGNAME': 'cltbld',
14:19:34 INFO - 'MAIL': '/var/mail/cltbld',
14:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:34 INFO - 'MOZ_NO_REMOTE': '1',
14:19:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:34 INFO - 'NO_EM_RESTART': '1',
14:19:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:34 INFO - 'PWD': '/builds/slave/test',
14:19:34 INFO - 'SHELL': '/bin/bash',
14:19:34 INFO - 'SHLVL': '1',
14:19:34 INFO - 'TERM': 'linux',
14:19:34 INFO - 'TMOUT': '86400',
14:19:34 INFO - 'USER': 'cltbld',
14:19:34 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:34 INFO - '_': '/tools/buildbot/bin/python'}
14:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple
14:19:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
14:19:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
14:19:35 INFO - Return code: 0
14:19:35 INFO - Running post-action listener: _resource_record_post_action
14:19:35 INFO - Running post-action listener: _start_resource_monitoring
14:19:35 INFO - Starting resource monitoring.
14:19:35 INFO - [mozharness: 2017-11-25 22:19:35.124182Z] Finished create-virtualenv step (success)
14:19:35 INFO - [mozharness: 2017-11-25 22:19:35.124693Z] Running install step.
14:19:35 INFO - Running pre-action listener: _resource_record_pre_action
14:19:35 INFO - Running main action method: install
14:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
14:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
14:19:35 INFO - Reading from file tmpfile_stdout
14:19:35 INFO - Reading from file tmpfile_stderr
14:19:35 INFO - Detecting whether we're running mozinstall >=1.0...
14:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
14:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
14:19:35 INFO - Reading from file tmpfile_stdout
14:19:35 INFO - Output received:
14:19:35 INFO - Usage: mozinstall [options] installer
14:19:35 INFO - Options:
14:19:35 INFO - -h, --help show this help message and exit
14:19:35 INFO - -d DEST, --destination=DEST
14:19:35 INFO - Directory to install application into. [default:
14:19:35 INFO - "/builds/slave/test"]
14:19:35 INFO - --app=APP Application being installed. [default: firefox]
14:19:35 INFO - mkdir: /builds/slave/test/build/application
14:19:35 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']
14:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
14:19:54 INFO - Reading from file tmpfile_stdout
14:19:54 INFO - Output received:
14:19:54 INFO - /builds/slave/test/build/application/firefox/firefox
14:19:54 INFO - Running post-action listener: _resource_record_post_action
14:19:54 INFO - [mozharness: 2017-11-25 22:19:54.193328Z] Finished install step (success)
14:19:54 INFO - [mozharness: 2017-11-25 22:19:54.193469Z] Running setup-mitmproxy step.
14:19:54 INFO - Running pre-action listener: _resource_record_pre_action
14:19:54 INFO - Running main action method: setup_mitmproxy
14:19:54 INFO - Skipping: mitmproxy is not required
14:19:54 INFO - Running post-action listener: _resource_record_post_action
14:19:54 INFO - [mozharness: 2017-11-25 22:19:54.193990Z] Finished setup-mitmproxy step (success)
14:19:54 INFO - [mozharness: 2017-11-25 22:19:54.194091Z] Running run-tests step.
14:19:54 INFO - Running pre-action listener: _resource_record_pre_action
14:19:54 INFO - Running pre-action listener: _set_gcov_prefix
14:19:54 INFO - Running main action method: run_tests
14:19:54 WARNING - Try message not found.
14:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
14:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
14:19:54 INFO - Python 2.7.3
14:19:54 INFO - Return code: 0
14:19:54 INFO - grabbing minidump binary from tooltool
14:19:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
14:19:54 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 0x1e32600>, '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 0x1e33500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e33990>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
14:19:54 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
14:19:54 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
14:19:54 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
14:19:54 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
14:19:54 INFO - Return code: 0
14:19:54 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
14:19:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
14:19:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
14:19:54 INFO - ENV: RUST_BACKTRACE is now full
14:19:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
14:19:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
14:19:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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
14:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --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
14:19:54 INFO - Using env: {'DISPLAY': ':0',
14:19:54 INFO - 'HOME': '/home/cltbld',
14:19:54 INFO - 'LANG': 'en_US.UTF-8',
14:19:54 INFO - 'LANGUAGE': 'en_US:en',
14:19:54 INFO - 'LOGNAME': 'cltbld',
14:19:54 INFO - 'MAIL': '/var/mail/cltbld',
14:19:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
14:19:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
14:19:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:19:54 INFO - 'MOZ_NO_REMOTE': '1',
14:19:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
14:19:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:19:54 INFO - 'NO_EM_RESTART': '1',
14:19:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:19:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:19:54 INFO - 'PWD': '/builds/slave/test',
14:19:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
14:19:54 INFO - 'RUST_BACKTRACE': 'full',
14:19:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
14:19:54 INFO - 'SHELL': '/bin/bash',
14:19:54 INFO - 'SHLVL': '1',
14:19:54 INFO - 'TERM': 'linux',
14:19:54 INFO - 'TMOUT': '86400',
14:19:54 INFO - 'USER': 'cltbld',
14:19:54 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:19:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:19:54 INFO - '_': '/tools/buildbot/bin/python'}
14:19:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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
14:19:54 INFO - mozversion application_buildid: 20171125211904
14:19:54 INFO - mozversion application_changeset: f045ac9f76cfb985032050f74b9d9ba20c216e32
14:19:54 INFO - mozversion application_display_name: Nightly
14:19:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
14:19:54 INFO - mozversion application_name: Firefox
14:19:54 INFO - mozversion application_remotingname: firefox
14:19:54 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
14:19:54 INFO - mozversion application_vendor: Mozilla
14:19:54 INFO - mozversion application_version: 59.0a1
14:19:54 INFO - mozversion platform_buildid: 20171125211904
14:19:54 INFO - mozversion platform_changeset: f045ac9f76cfb985032050f74b9d9ba20c216e32
14:19:54 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
14:19:54 INFO - mozversion platform_version: 59.0a1
14:19:54 INFO - using testdate: 1511648394
14:19:54 INFO - actual date: 1511648394
14:19:54 INFO - starting webserver on 'localhost:47365'
14:19:54 INFO - SUITE-START | Running 3 tests
14:19:54 INFO - TEST-START | basic_compositor_video
14:19:54 INFO - Initialising browser for basic_compositor_video test...
14:19:54 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
14:19:54 INFO - Installing Add-ons
14:19:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47365/getInfo.html -profile /tmp/tmpPz1BCt/profile
14:19:54 INFO - TEST-INFO | started process 23068 (/builds/slave/test/build/application/firefox/firefox http://localhost:47365/getInfo.html)
14:20:06 INFO - TEST-INFO | 23068: exit 0
14:20:06 INFO - Browser initialized.
14:20:06 INFO - Running cycle 1/1 for basic_compositor_video test...
14:20:06 INFO - Using env: {'DISPLAY': ':0',
14:20:06 INFO - 'HOME': '/home/cltbld',
14:20:06 INFO - 'JSGC_DISABLE_POISONING': '1',
14:20:06 INFO - 'LANG': 'en_US.UTF-8',
14:20:06 INFO - 'LANGUAGE': 'en_US:en',
14:20:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
14:20:06 INFO - 'LOGNAME': 'cltbld',
14:20:06 INFO - 'MAIL': '/var/mail/cltbld',
14:20:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
14:20:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
14:20:06 INFO - 'MOZ_CRASHREPORTER': '1',
14:20:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:20:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
14:20:06 INFO - 'MOZ_NO_REMOTE': '1',
14:20:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
14:20:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:20:06 INFO - 'NO_EM_RESTART': '1',
14:20:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:20:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:20:06 INFO - 'PWD': '/builds/slave/test',
14:20:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
14:20:06 INFO - 'RUST_BACKTRACE': 'full',
14:20:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
14:20:06 INFO - 'SHELL': '/bin/bash',
14:20:06 INFO - 'SHLVL': '1',
14:20:06 INFO - 'TERM': 'linux',
14:20:06 INFO - 'TMOUT': '86400',
14:20:06 INFO - 'USER': 'cltbld',
14:20:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:20:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:20:06 INFO - '_': '/tools/buildbot/bin/python'}
14:20:06 INFO - TEST-INFO | started process 23360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPz1BCt/profile)
14:20:06 INFO - PID 23360 |
14:20:06 INFO - PID 23360 | (/builds/slave/test/build/application/firefox/firefox:23411): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
14:20:06 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6818862107623316 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6766680637049458 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.741842716192687 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7174935622317589 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 3.3383648498331486 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.125020618556701 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.6676039119804407 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6111191335740096 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.895982318271121 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635380281690134 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 9.617852564102565 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.95099502487563 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.3649431818182 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.695588235294101 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.608130252100842 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.049006024096398 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.541673076923074 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.051077348066272 ms/frame
14:20:53 INFO - PID 23360 |
14:20:53 INFO - PID 23360 | Cycle 1(1): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:21:39 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6856095505617978 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6726170568561876 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7488024475524473 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7096452991452997 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.111171232876713 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.256361702127659 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.5930538922155697 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.492268760907504 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7268893129771055 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.651101694915249 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 9.526206349206353 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.221589861751148 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.364602272727263 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.934016393442638 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450435684647305 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.561965317919082 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584285714285693 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.111194444444441 ms/frame
14:21:39 INFO - PID 23360 |
14:21:39 INFO - PID 23360 | Cycle 1(2): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:22:25 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6826640493550196 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.670283806343907 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7710537190082645 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7354379878577617 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1673125 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.050850202429151 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.654866017052378 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.590987432675039 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7380879541109 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.619213483146074 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 10.383564013840825 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 10.26033333333333 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.153773234200736 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754892473118298 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.349917695473247 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.04903614457831 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.495804597701174 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.929672131147527 ms/frame
14:22:25 INFO - PID 23360 |
14:22:25 INFO - PID 23360 | Cycle 1(3): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:23:11 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6811988795518207 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682568807339455 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7438146426496222 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7350997398091932 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.149571230982018 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.2128315789473705 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.546382978723402 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4664038128249595 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.747835249042144 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.668201133144476 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 10.03693979933111 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.347009345794385 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.323301886792436 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.873315217391317 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.663291139240494 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.42829192546585 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.498026819923373 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.929863387978147 ms/frame
14:23:11 INFO - PID 23360 |
14:23:11 INFO - PID 23360 | Cycle 1(4): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:23:57 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6923857868020304 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669595158597663 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7495014577259478 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7643783068783072 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071227951153325 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.2474840764331185 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.4888023255813962 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.425599315068492 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.771298076923078 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635098591549304 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 9.678 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.302395348837207 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.45332061068701 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.991510989011 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.295922131147519 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907916666666702 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583262548262557 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050911602209926 ms/frame
14:23:57 INFO - PID 23360 |
14:23:57 INFO - PID 23360 | Cycle 1(5): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:24:44 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827453729669097 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.671675020885547 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7437884950610107 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7339341421143848 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.179394150417828 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1586902286902285 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.4929685681024436 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5095175438596478 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.8493079922027285 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.9187869822485295 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 10.001783333333332 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.569880382775118 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.410171102661597 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.755940860215071 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.555585774058592 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.91005952380952 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.410988593155908 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113944444444416 ms/frame
14:24:44 INFO - PID 23360 |
14:24:44 INFO - PID 23360 | Cycle 1(6): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:25:30 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6839309764309767 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.672621237458194 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.735159051474841 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6980730050933792 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.220007032348806 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.100040983606557 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.5219542253521126 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.468084922010397 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.770538461538462 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.407540540540543 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 10.17001694915255 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 10.208035714285703 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.539249999999992 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.990879120879141 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.501333333333331 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.840236686390533 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.410095057034233 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.870135869565194 ms/frame
14:25:30 INFO - PID 23360 |
14:25:30 INFO - PID 23360 | Cycle 1(7): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:26:16 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6868100056211355 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713366750208853 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7547953216374268 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7892933810375673 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1624687933425815 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.876579457364342 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.5430578512396687 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4856533101045284 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.84890838206628 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618469101123593 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 9.741704545454544 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.435306603773585 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.32454716981131 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754704301075268 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347427983539077 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.834911242603543 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.6762645914397 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.175446927374296 ms/frame
14:26:16 INFO - PID 23360 |
14:26:16 INFO - PID 23360 | Cycle 1(8): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:27:02 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6850084222346997 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6718045112781958 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.775340236686391 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7548552631578938 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1279367262723525 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.5165729349736385 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.4857084785133563 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4254452054794506 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715971428571425 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683821022727278 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 10.1047138047138 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 10.002975000000006 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.36583333333334 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.934562841530022 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.502729166666661 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.270122699386496 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.366155303030306 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.755591397849468 ms/frame
14:27:02 INFO - PID 23360 |
14:27:02 INFO - PID 23360 | Cycle 1(9): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:27:48 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6849438517686695 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6732023411371237 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7915791044776117 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7334748700173306 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9695171957671955 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.310581896551725 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.641589805825245 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.534505300353355 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.791833976833973 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.667903682719548 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 9.710485436893206 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.350560747663554 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.631395348837215 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930573770491794 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.297028688524614 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83831360946743 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452003816793905 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.98928571428569 ms/frame
14:27:48 INFO - PID 23360 |
14:27:48 INFO - PID 23360 | Cycle 1(10): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:28:34 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6864811691961776 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6661490424646128 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7334142114384747 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7277029360967195 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8623487773487772 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.246645435244162 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.6866769041769065 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6442987249544583 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693130929791276 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.636098591549292 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 9.936125827814566 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.61973557692309 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.496896551724133 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.933114754098371 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.663206751054853 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.123545454545466 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452404580152667 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.698288770053447 ms/frame
14:28:34 INFO - PID 23360 |
14:28:34 INFO - PID 23360 | Cycle 1(11): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:29:21 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6799524076147816 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6703130217028384 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.772203189604253 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7301859861591697 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_startup = 4.054554054054055 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8627992277992282 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_startup = 3.6111973525872427 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1_inclip = 3.579803220035779 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.83842412451362 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620084269662922 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_startup = 9.838557377049176 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.480p.60fps.webm_scale_2_inclip = 9.303883720930228 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.583281853281852 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.870027173913039 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.345823045267505 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.561098265895952 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45162213740457 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.753145161290318 ms/frame
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | Cycle 1(12): loaded http://localhost:47365/tests/video/video_playback.html (next: http://localhost:47365/tests/video/video_playback.html)
14:29:21 INFO - PID 23360 | __start_tp_report
14:29:21 INFO - PID 23360 | _x_x_mozilla_page_load
14:29:21 INFO - PID 23360 | _x_x_mozilla_page_load_details
14:29:21 INFO - PID 23360 | |i|pagename|runs|
14:29:21 INFO - PID 23360 | |0;240p.120fps.mp4_scale_1_startup;1.6818862107623316;1.6856095505617978;1.6826640493550196;1.6811988795518207;1.6923857868020304;1.6827453729669097;1.6839309764309767;1.6868100056211355;1.6850084222346997;1.6849438517686695;1.6864811691961776;1.6799524076147816
14:29:21 INFO - PID 23360 | |1;240p.120fps.mp4_scale_1_inclip;1.6766680637049458;1.6726170568561876;1.670283806343907;1.6682568807339455;1.669595158597663;1.671675020885547;1.672621237458194;1.6713366750208853;1.6718045112781958;1.6732023411371237;1.6661490424646128;1.6703130217028384
14:29:21 INFO - PID 23360 | |2;240p.120fps.mp4_scale_1.1_startup;1.741842716192687;1.7488024475524473;1.7710537190082645;1.7438146426496222;1.7495014577259478;1.7437884950610107;1.735159051474841;1.7547953216374268;1.775340236686391;1.7915791044776117;1.7334142114384747;1.772203189604253
14:29:21 INFO - PID 23360 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7174935622317589;1.7096452991452997;1.7354379878577617;1.7350997398091932;1.7643783068783072;1.7339341421143848;1.6980730050933792;1.7892933810375673;1.7548552631578938;1.7334748700173306;1.7277029360967195;1.7301859861591697
14:29:21 INFO - PID 23360 | |4;240p.120fps.mp4_scale_2_startup;3.3383648498331486;4.111171232876713;4.1673125;4.149571230982018;4.071227951153325;4.179394150417828;4.220007032348806;4.1624687933425815;4.1279367262723525;3.9695171957671955;3.8623487773487772;4.054554054054055
14:29:21 INFO - PID 23360 | |5;240p.120fps.mp4_scale_2_inclip;4.125020618556701;4.256361702127659;4.050850202429151;4.2128315789473705;4.2474840764331185;4.1586902286902285;4.100040983606557;3.876579457364342;3.5165729349736385;4.310581896551725;4.246645435244162;3.8627992277992282
14:29:21 INFO - PID 23360 | |6;480p.60fps.webm_scale_1_startup;3.6676039119804407;3.5930538922155697;3.654866017052378;3.546382978723402;3.4888023255813962;3.4929685681024436;3.5219542253521126;3.5430578512396687;3.4857084785133563;3.641589805825245;3.6866769041769065;3.6111973525872427
14:29:21 INFO - PID 23360 | |7;480p.60fps.webm_scale_1_inclip;3.6111191335740096;3.492268760907504;3.590987432675039;3.4664038128249595;3.425599315068492;3.5095175438596478;3.468084922010397;3.4856533101045284;3.4254452054794506;3.534505300353355;3.6442987249544583;3.579803220035779
14:29:21 INFO - PID 23360 | |8;480p.60fps.webm_scale_1.1_startup;5.895982318271121;5.7268893129771055;5.7380879541109;5.747835249042144;5.771298076923078;5.8493079922027285;5.770538461538462;5.84890838206628;5.715971428571425;5.791833976833973;5.693130929791276;5.83842412451362
14:29:21 INFO - PID 23360 | |9;480p.60fps.webm_scale_1.1_inclip;5.635380281690134;5.651101694915249;5.619213483146074;5.668201133144476;5.635098591549304;5.9187869822485295;5.407540540540543;5.618469101123593;5.683821022727278;5.667903682719548;5.636098591549292;5.620084269662922
14:29:21 INFO - PID 23360 | |10;480p.60fps.webm_scale_2_startup;9.617852564102565;9.526206349206353;10.383564013840825;10.03693979933111;9.678;10.001783333333332;10.17001694915255;9.741704545454544;10.1047138047138;9.710485436893206;9.936125827814566;9.838557377049176
14:29:21 INFO - PID 23360 | |11;480p.60fps.webm_scale_2_inclip;9.95099502487563;9.221589861751148;10.26033333333333;9.347009345794385;9.302395348837207;9.569880382775118;10.208035714285703;9.435306603773585;10.002975000000006;9.350560747663554;9.61973557692309;9.303883720930228
14:29:21 INFO - PID 23360 | |12;1080p.60fps.mp4_scale_1_startup;11.3649431818182;11.364602272727263;11.153773234200736;11.323301886792436;11.45332061068701;11.410171102661597;11.539249999999992;11.32454716981131;11.36583333333334;11.631395348837215;11.496896551724133;11.583281853281852
14:29:21 INFO - PID 23360 | |13;1080p.60fps.mp4_scale_1_inclip;10.695588235294101;10.934016393442638;10.754892473118298;10.873315217391317;10.991510989011;10.755940860215071;10.990879120879141;10.754704301075268;10.934562841530022;10.930573770491794;10.933114754098371;10.870027173913039
14:29:21 INFO - PID 23360 | |14;1080p.60fps.mp4_scale_1.1_startup;12.608130252100842;12.450435684647305;12.349917695473247;12.663291139240494;12.295922131147519;12.555585774058592;12.501333333333331;12.347427983539077;12.502729166666661;12.297028688524614;12.663206751054853;12.345823045267505
14:29:21 INFO - PID 23360 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.049006024096398;11.561965317919082;12.04903614457831;12.42829192546585;11.907916666666702;11.91005952380952;11.840236686390533;11.834911242603543;12.270122699386496;11.83831360946743;12.123545454545466;11.561098265895952
14:29:21 INFO - PID 23360 | |16;1080p.60fps.mp4_scale_2_startup;11.541673076923074;11.584285714285693;11.495804597701174;11.498026819923373;11.583262548262557;11.410988593155908;11.410095057034233;11.6762645914397;11.366155303030306;11.452003816793905;11.452404580152667;11.45162213740457
14:29:21 INFO - PID 23360 | |17;1080p.60fps.mp4_scale_2_inclip;11.051077348066272;11.111194444444441;10.929672131147527;10.929863387978147;11.050911602209926;11.113944444444416;10.870135869565194;11.175446927374296;10.755591397849468;10.98928571428569;10.698288770053447;10.753145161290318
14:29:21 INFO - PID 23360 | __end_tp_report
14:29:21 INFO - PID 23360 | __start_cc_report
14:29:21 INFO - PID 23360 | _x_x_mozilla_cycle_collect,607
14:29:21 INFO - PID 23360 | __end_cc_report
14:29:21 INFO - PID 23360 | __startTimestamp1511648961285__endTimestamp
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | ------- Summary: start -------
14:29:21 INFO - PID 23360 | Number of tests: 18
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
14:29:21 INFO - PID 23360 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.2%) stddev-sans-first:0.00
14:29:21 INFO - PID 23360 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.75 stddev:0.02 (1.0%) stddev-sans-first:0.02
14:29:21 INFO - PID 23360 | Values: 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.8 1.8 1.8 1.7 1.8
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.74 Median:1.73 stddev:0.02 (1.4%) stddev-sans-first:0.02
14:29:21 INFO - PID 23360 | Values: 1.7 1.7 1.7 1.7 1.8 1.7 1.7 1.8 1.8 1.7 1.7 1.7
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.03 Median:4.14 stddev:0.24 (5.8%) stddev-sans-first:0.10
14:29:21 INFO - PID 23360 | Values: 3.3 4.1 4.2 4.1 4.1 4.2 4.2 4.2 4.1 4.0 3.9 4.1
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.08 Median:4.19 stddev:0.23 (5.5%) stddev-sans-first:0.24
14:29:21 INFO - PID 23360 | Values: 4.1 4.3 4.1 4.2 4.2 4.2 4.1 3.9 3.5 4.3 4.2 3.9
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.58 Median:3.60 stddev:0.07 (2.1%) stddev-sans-first:0.07
14:29:21 INFO - PID 23360 | Values: 3.7 3.6 3.7 3.5 3.5 3.5 3.5 3.5 3.5 3.6 3.7 3.6
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.52 Median:3.52 stddev:0.07 (2.1%) stddev-sans-first:0.07
14:29:21 INFO - PID 23360 | Values: 3.6 3.5 3.6 3.5 3.4 3.5 3.5 3.5 3.4 3.5 3.6 3.6
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.78 Median:5.78 stddev:0.06 (1.1%) stddev-sans-first:0.05
14:29:21 INFO - PID 23360 | Values: 5.9 5.7 5.7 5.7 5.8 5.8 5.8 5.8 5.7 5.8 5.7 5.8
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.65 Median:5.64 stddev:0.11 (2.0%) stddev-sans-first:0.12
14:29:21 INFO - PID 23360 | Values: 5.6 5.7 5.6 5.7 5.6 5.9 5.4 5.6 5.7 5.7 5.6 5.6
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.90 Median:9.65 stddev:0.25 (2.6%) stddev-sans-first:0.25
14:29:21 INFO - PID 23360 | Values: 9.6 9.5 10.4 10.0 9.7 10.0 10.2 9.7 10.1 9.7 9.9 9.8
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.63 Median:9.35 stddev:0.38 (4.0%) stddev-sans-first:0.38
14:29:21 INFO - PID 23360 | Values: 10.0 9.2 10.3 9.3 9.3 9.6 10.2 9.4 10.0 9.4 9.6 9.3
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.42 Median:11.43 stddev:0.13 (1.2%) stddev-sans-first:0.14
14:29:21 INFO - PID 23360 | Values: 11.4 11.4 11.2 11.3 11.5 11.4 11.5 11.3 11.4 11.6 11.5 11.6
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.87 Median:10.93 stddev:0.10 (0.9%) stddev-sans-first:0.09
14:29:21 INFO - PID 23360 | Values: 10.7 10.9 10.8 10.9 11.0 10.8 11.0 10.8 10.9 10.9 10.9 10.9
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.47 Median:12.50 stddev:0.14 (1.1%) stddev-sans-first:0.14
14:29:21 INFO - PID 23360 | Values: 12.6 12.5 12.3 12.7 12.3 12.6 12.5 12.3 12.5 12.3 12.7 12.3
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.95 Median:11.98 stddev:0.26 (2.1%) stddev-sans-first:0.27
14:29:21 INFO - PID 23360 | Values: 12.0 11.6 12.0 12.4 11.9 11.9 11.8 11.8 12.3 11.8 12.1 11.6
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.49 Median:11.50 stddev:0.09 (0.8%) stddev-sans-first:0.09
14:29:21 INFO - PID 23360 | Values: 11.5 11.6 11.5 11.5 11.6 11.4 11.4 11.7 11.4 11.5 11.5 11.5
14:29:21 INFO - PID 23360 |
14:29:21 INFO - PID 23360 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.95 Median:11.02 stddev:0.16 (1.4%) stddev-sans-first:0.16
14:29:21 INFO - PID 23360 | Values: 11.1 11.1 10.9 10.9 11.1 11.1 10.9 11.2 10.8 11.0 10.7 10.8
14:29:21 INFO - PID 23360 | -------- Summary: end --------
14:29:21 INFO - PID 23360 |
14:29:26 INFO - TEST-INFO | 23360: exit 0
14:29:26 INFO - TEST-OK | basic_compositor_video | took 572192ms
14:29:26 INFO - TEST-START | glvideo
14:29:26 INFO - Initialising browser for glvideo test...
14:29:26 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
14:29:26 INFO - Installing Add-ons
14:29:26 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47365/getInfo.html -profile /tmp/tmpgmcqgj/profile
14:29:26 INFO - TEST-INFO | started process 24614 (/builds/slave/test/build/application/firefox/firefox http://localhost:47365/getInfo.html)
14:29:38 INFO - TEST-INFO | 24614: exit 0
14:29:38 INFO - Browser initialized.
14:29:38 INFO - Running cycle 1/1 for glvideo test...
14:29:38 INFO - Using env: {'DISPLAY': ':0',
14:29:38 INFO - 'HOME': '/home/cltbld',
14:29:38 INFO - 'JSGC_DISABLE_POISONING': '1',
14:29:38 INFO - 'LANG': 'en_US.UTF-8',
14:29:38 INFO - 'LANGUAGE': 'en_US:en',
14:29:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
14:29:38 INFO - 'LOGNAME': 'cltbld',
14:29:38 INFO - 'MAIL': '/var/mail/cltbld',
14:29:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
14:29:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
14:29:38 INFO - 'MOZ_CRASHREPORTER': '1',
14:29:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:29:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
14:29:38 INFO - 'MOZ_NO_REMOTE': '1',
14:29:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
14:29:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:29:38 INFO - 'NO_EM_RESTART': '1',
14:29:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:29:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:29:38 INFO - 'PWD': '/builds/slave/test',
14:29:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
14:29:38 INFO - 'RUST_BACKTRACE': 'full',
14:29:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
14:29:38 INFO - 'SHELL': '/bin/bash',
14:29:38 INFO - 'SHLVL': '1',
14:29:38 INFO - 'TERM': 'linux',
14:29:38 INFO - 'TMOUT': '86400',
14:29:38 INFO - 'USER': 'cltbld',
14:29:38 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:29:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:29:38 INFO - '_': '/tools/buildbot/bin/python'}
14:29:38 INFO - TEST-INFO | started process 24953 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgmcqgj/profile)
14:29:39 INFO - PID 24953 |
14:29:39 INFO - PID 24953 | (/builds/slave/test/build/application/firefox/firefox:25004): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
14:29:39 INFO - PID 24953 |
14:29:40 INFO - PID 24953 | [talos glvideo result] Mean tick time across 100 ticks: 6.80425 ms
14:29:40 INFO - PID 24953 | Cycle 1(1): loaded http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html)
14:29:42 INFO - PID 24953 | [talos glvideo result] Mean tick time across 100 ticks: 6.5611500000000005 ms
14:29:42 INFO - PID 24953 | Cycle 1(2): loaded http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html)
14:29:43 INFO - PID 24953 | [talos glvideo result] Mean tick time across 100 ticks: 6.56905 ms
14:29:43 INFO - PID 24953 | Cycle 1(3): loaded http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html)
14:29:44 INFO - PID 24953 | [talos glvideo result] Mean tick time across 100 ticks: 6.56995 ms
14:29:44 INFO - PID 24953 | Cycle 1(4): loaded http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html)
14:29:46 INFO - PID 24953 | [talos glvideo result] Mean tick time across 100 ticks: 6.5466 ms
14:29:46 INFO - PID 24953 | Cycle 1(5): loaded http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47365/tests/webgl/benchmarks/video/video_upload.html)
14:29:46 INFO - PID 24953 | __start_tp_report
14:29:46 INFO - PID 24953 | _x_x_mozilla_page_load
14:29:46 INFO - PID 24953 | _x_x_mozilla_page_load_details
14:29:46 INFO - PID 24953 | |i|pagename|runs|
14:29:46 INFO - PID 24953 | |0;Mean tick time across 100 ticks: ;6.80425;6.5611500000000005;6.56905;6.56995;6.5466
14:29:46 INFO - PID 24953 | __end_tp_report
14:29:46 INFO - PID 24953 | __start_cc_report
14:29:46 INFO - PID 24953 | _x_x_mozilla_cycle_collect,277
14:29:46 INFO - PID 24953 | __end_cc_report
14:29:46 INFO - PID 24953 | __startTimestamp1511648986465__endTimestamp
14:29:46 INFO - PID 24953 |
14:29:46 INFO - PID 24953 | ------- Summary: start -------
14:29:46 INFO - PID 24953 | Number of tests: 1
14:29:46 INFO - PID 24953 |
14:29:46 INFO - PID 24953 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.61 Median:6.57 stddev:0.11 (1.7%) stddev-sans-first:0.01
14:29:46 INFO - PID 24953 | Values: 6.8 6.6 6.6 6.6 6.5
14:29:46 INFO - PID 24953 | -------- Summary: end --------
14:29:46 INFO - PID 24953 |
14:29:46 INFO - PID 24953 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
14:29:52 INFO - TEST-INFO | 24953: exit 0
14:29:52 INFO - TEST-OK | glvideo | took 25340ms
14:29:52 INFO - TEST-START | displaylist_mutate
14:29:52 INFO - Initialising browser for displaylist_mutate test...
14:29:52 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
14:29:52 INFO - Installing Add-ons
14:29:52 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47365/getInfo.html -profile /tmp/tmpfaIhOT/profile
14:29:52 INFO - TEST-INFO | started process 25180 (/builds/slave/test/build/application/firefox/firefox http://localhost:47365/getInfo.html)
14:30:03 INFO - TEST-INFO | 25180: exit 0
14:30:03 INFO - Browser initialized.
14:30:03 INFO - Running cycle 1/1 for displaylist_mutate test...
14:30:03 INFO - Using env: {'DISPLAY': ':0',
14:30:03 INFO - 'HOME': '/home/cltbld',
14:30:03 INFO - 'JSGC_DISABLE_POISONING': '1',
14:30:03 INFO - 'LANG': 'en_US.UTF-8',
14:30:03 INFO - 'LANGUAGE': 'en_US:en',
14:30:03 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
14:30:03 INFO - 'LOGNAME': 'cltbld',
14:30:03 INFO - 'MAIL': '/var/mail/cltbld',
14:30:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
14:30:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
14:30:03 INFO - 'MOZ_CRASHREPORTER': '1',
14:30:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
14:30:03 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
14:30:03 INFO - 'MOZ_NO_REMOTE': '1',
14:30:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
14:30:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
14:30:03 INFO - 'NO_EM_RESTART': '1',
14:30:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
14:30:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
14:30:03 INFO - 'PWD': '/builds/slave/test',
14:30:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
14:30:03 INFO - 'RUST_BACKTRACE': 'full',
14:30:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
14:30:03 INFO - 'SHELL': '/bin/bash',
14:30:03 INFO - 'SHLVL': '1',
14:30:03 INFO - 'TERM': 'linux',
14:30:03 INFO - 'TMOUT': '86400',
14:30:03 INFO - 'USER': 'cltbld',
14:30:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336',
14:30:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
14:30:03 INFO - '_': '/tools/buildbot/bin/python'}
14:30:03 INFO - TEST-INFO | started process 25499 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfaIhOT/profile)
14:30:04 INFO - PID 25499 |
14:30:04 INFO - PID 25499 | (/builds/slave/test/build/application/firefox/firefox:25550): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
14:30:04 INFO - PID 25499 |
14:30:09 INFO - PID 25499 | Cycle 1(1): loaded http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html)
14:30:15 INFO - PID 25499 | Cycle 1(2): loaded http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html)
14:30:15 INFO - PID 25499 | Waiting for idle-callback
14:30:22 INFO - PID 25499 | Cycle 1(3): loaded http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html)
14:30:22 INFO - PID 25499 | Waiting for idle-callback
14:30:28 INFO - PID 25499 | Cycle 1(4): loaded http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html)
14:30:34 INFO - PID 25499 | Cycle 1(5): loaded http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:47365/tests/layout/benchmarks/displaylist_mutate.html)
14:30:34 INFO - PID 25499 | __start_tp_report
14:30:34 INFO - PID 25499 | _x_x_mozilla_page_load
14:30:34 INFO - PID 25499 | _x_x_mozilla_page_load_details
14:30:34 INFO - PID 25499 | |i|pagename|runs|
14:30:34 INFO - PID 25499 | |0;/displaylist_mutate.html;4804.57;4787.955;5151.200000000001;5237.110000000001;5300.695
14:30:34 INFO - PID 25499 | __end_tp_report
14:30:34 INFO - PID 25499 | __start_cc_report
14:30:34 INFO - PID 25499 | _x_x_mozilla_cycle_collect,268
14:30:34 INFO - PID 25499 | __end_cc_report
14:30:34 INFO - PID 25499 | __startTimestamp1511649034621__endTimestamp
14:30:34 INFO - PID 25499 |
14:30:34 INFO - PID 25499 | ------- Summary: start -------
14:30:34 INFO - PID 25499 | Number of tests: 1
14:30:34 INFO - PID 25499 |
14:30:34 INFO - PID 25499 | [#0] /displaylist_mutate.html Cycles:5 Average:5056.31 Median:5151.20 stddev:243.31 (4.7%) stddev-sans-first:229.19
14:30:34 INFO - PID 25499 | Values: 4804.6 4788.0 5151.2 5237.1 5300.7
14:30:34 INFO - PID 25499 | -------- Summary: end --------
14:30:34 INFO - PID 25499 |
14:30:34 INFO - PID 25499 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
14:30:40 INFO - TEST-INFO | 25499: exit 0
14:30:40 INFO - TEST-OK | displaylist_mutate | took 48094ms
14:30:40 INFO - SUITE-END | took 645s
14:30:40 INFO - Completed test suite (00:10:45)
14:30:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.603676942300193, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6818862107623316, 1.6856095505617978, 1.6826640493550196, 1.6811988795518207, 1.6923857868020304, 1.6827453729669097, 1.6839309764309767, 1.6868100056211355, 1.6850084222346997, 1.6849438517686695, 1.6864811691961776, 1.6799524076147816], "value": 1.6849438517686695, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6766680637049458, 1.6726170568561876, 1.670283806343907, 1.6682568807339455, 1.669595158597663, 1.671675020885547, 1.672621237458194, 1.6713366750208853, 1.6718045112781958, 1.6732023411371237, 1.6661490424646128, 1.6703130217028384], "value": 1.6713366750208853, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.741842716192687, 1.7488024475524473, 1.7710537190082645, 1.7438146426496222, 1.7495014577259478, 1.7437884950610107, 1.735159051474841, 1.7547953216374268, 1.775340236686391, 1.7915791044776117, 1.7334142114384747, 1.772203189604253], "value": 1.7495014577259478, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7174935622317589, 1.7096452991452997, 1.7354379878577617, 1.7350997398091932, 1.7643783068783072, 1.7339341421143848, 1.6980730050933792, 1.7892933810375673, 1.7548552631578938, 1.7334748700173306, 1.7277029360967195, 1.7301859861591697], "value": 1.7339341421143848, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.3383648498331486, 4.111171232876713, 4.1673125, 4.149571230982018, 4.071227951153325, 4.179394150417828, 4.220007032348806, 4.1624687933425815, 4.1279367262723525, 3.9695171957671955, 3.8623487773487772, 4.054554054054055], "value": 4.1279367262723525, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.125020618556701, 4.256361702127659, 4.050850202429151, 4.2128315789473705, 4.2474840764331185, 4.1586902286902285, 4.100040983606557, 3.876579457364342, 3.5165729349736385, 4.310581896551725, 4.246645435244162, 3.8627992277992282], "value": 4.1586902286902285, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.6676039119804407, 3.5930538922155697, 3.654866017052378, 3.546382978723402, 3.4888023255813962, 3.4929685681024436, 3.5219542253521126, 3.5430578512396687, 3.4857084785133563, 3.641589805825245, 3.6866769041769065, 3.6111973525872427], "value": 3.546382978723402, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.6111191335740096, 3.492268760907504, 3.590987432675039, 3.4664038128249595, 3.425599315068492, 3.5095175438596478, 3.468084922010397, 3.4856533101045284, 3.4254452054794506, 3.534505300353355, 3.6442987249544583, 3.579803220035779], "value": 3.492268760907504, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.895982318271121, 5.7268893129771055, 5.7380879541109, 5.747835249042144, 5.771298076923078, 5.8493079922027285, 5.770538461538462, 5.84890838206628, 5.715971428571425, 5.791833976833973, 5.693130929791276, 5.83842412451362], "value": 5.770538461538462, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.635380281690134, 5.651101694915249, 5.619213483146074, 5.668201133144476, 5.635098591549304, 5.9187869822485295, 5.407540540540543, 5.618469101123593, 5.683821022727278, 5.667903682719548, 5.636098591549292, 5.620084269662922], "value": 5.636098591549292, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.617852564102565, 9.526206349206353, 10.383564013840825, 10.03693979933111, 9.678, 10.001783333333332, 10.17001694915255, 9.741704545454544, 10.1047138047138, 9.710485436893206, 9.936125827814566, 9.838557377049176], "value": 9.936125827814566, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.95099502487563, 9.221589861751148, 10.26033333333333, 9.347009345794385, 9.302395348837207, 9.569880382775118, 10.208035714285703, 9.435306603773585, 10.002975000000006, 9.350560747663554, 9.61973557692309, 9.303883720930228], "value": 9.435306603773585, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.3649431818182, 11.364602272727263, 11.153773234200736, 11.323301886792436, 11.45332061068701, 11.410171102661597, 11.539249999999992, 11.32454716981131, 11.36583333333334, 11.631395348837215, 11.496896551724133, 11.583281853281852], "value": 11.410171102661597, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.695588235294101, 10.934016393442638, 10.754892473118298, 10.873315217391317, 10.991510989011, 10.755940860215071, 10.990879120879141, 10.754704301075268, 10.934562841530022, 10.930573770491794, 10.933114754098371, 10.870027173913039], "value": 10.930573770491794, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.608130252100842, 12.450435684647305, 12.349917695473247, 12.663291139240494, 12.295922131147519, 12.555585774058592, 12.501333333333331, 12.347427983539077, 12.502729166666661, 12.297028688524614, 12.663206751054853, 12.345823045267505], "value": 12.450435684647305, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.049006024096398, 11.561965317919082, 12.04903614457831, 12.42829192546585, 11.907916666666702, 11.91005952380952, 11.840236686390533, 11.834911242603543, 12.270122699386496, 11.83831360946743, 12.123545454545466, 11.561098265895952], "value": 11.907916666666702, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.541673076923074, 11.584285714285693, 11.495804597701174, 11.498026819923373, 11.583262548262557, 11.410988593155908, 11.410095057034233, 11.6762645914397, 11.366155303030306, 11.452003816793905, 11.452404580152667, 11.45162213740457], "value": 11.452404580152667, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.051077348066272, 11.111194444444441, 10.929672131147527, 10.929863387978147, 11.050911602209926, 11.113944444444416, 10.870135869565194, 11.175446927374296, 10.755591397849468, 10.98928571428569, 10.698288770053447, 10.753145161290318], "value": 10.929863387978147, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.80425, 6.5611500000000005, 6.56905, 6.56995, 6.5466], "value": 6.5651, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4804.57, 4787.955, 5151.200000000001, 5237.110000000001, 5300.695], "value": 5194.155000000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
14:30:40 INFO - Return code: 0
14:30:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
14:30:40 INFO - # TBPL SUCCESS #
14:30:40 INFO - Running post-action listener: _package_coverage_data
14:30:40 INFO - Running post-action listener: _resource_record_post_action
14:30:40 INFO - [mozharness: 2017-11-25 22:30:40.521411Z] Finished run-tests step (success)
14:30:40 INFO - Running post-run listener: _resource_record_post_run
14:30:40 INFO - Total resource usage - Wall time: 665s; CPU: 36.0%; Read bytes: 2740224; Write bytes: 351547392; Read time: 440; Write time: 352920
14:30:40 INFO - TinderboxPrint: CPU usage
35.7%
14:30:40 INFO - TinderboxPrint: I/O read bytes / time
2,740,224 / 440
14:30:40 INFO - TinderboxPrint: I/O write bytes / time
351,547,392 / 352,920
14:30:40 INFO - TinderboxPrint: CPU idle
3,362.5 (64.4%)
14:30:40 INFO - TinderboxPrint: CPU system
307.6 (5.9%)
14:30:40 INFO - TinderboxPrint: CPU user
1,531.9 (29.3%)
14:30:40 INFO - TinderboxPrint: Swap in / out
0 / 0
14:30:40 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5644288; Read time: 0; Write time: 7132
14:30:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
14:30:40 INFO - run-tests - Wall time: 646s; CPU: 36.0%; Read bytes: 2736128; Write bytes: 343781376; Read time: 424; Write time: 345476
14:30:40 INFO - Running post-run listener: _upload_blobber_files
14:30:40 INFO - Blob upload gear active.
14:30:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
14:30:40 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
14:30:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
14:30:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
14:30:41 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
14:30:41 INFO - SNIMissingWarning
14:30:41 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
14:30:41 INFO - InsecurePlatformWarning
14:30:41 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.)
14:30:41 INFO - SubjectAltNameWarning
14:30:41 INFO - (blobuploader) - INFO - Open directory for files ...
14:30:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
14:30:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:30:41 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:30:41 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
14:30:41 INFO - InsecurePlatformWarning
14:30:41 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.)
14:30:41 INFO - SubjectAltNameWarning
14:30:41 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
14:30:41 INFO - InsecurePlatformWarning
14:30:42 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
14:30:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:30:42 INFO - (blobuploader) - INFO - Done attempting.
14:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
14:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:30:42 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
14:30:42 INFO - InsecurePlatformWarning
14:30:42 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.)
14:30:42 INFO - SubjectAltNameWarning
14:30:44 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
14:30:44 INFO - InsecurePlatformWarning
14:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
14:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:30:44 INFO - (blobuploader) - INFO - Done attempting.
14:30:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
14:30:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:30:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:30:44 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
14:30:44 INFO - InsecurePlatformWarning
14:30:44 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.)
14:30:44 INFO - SubjectAltNameWarning
14:30:45 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
14:30:45 INFO - InsecurePlatformWarning
14:30:45 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
14:30:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:30:45 INFO - (blobuploader) - INFO - Done attempting.
14:30:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
14:30:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
14:30:45 INFO - (blobuploader) - INFO - Uploading, attempt #1.
14:30:45 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
14:30:45 INFO - InsecurePlatformWarning
14:30:45 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.)
14:30:45 INFO - SubjectAltNameWarning
14:30:46 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
14:30:46 INFO - InsecurePlatformWarning
14:30:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
14:30:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
14:30:46 INFO - (blobuploader) - INFO - Done attempting.
14:30:46 INFO - (blobuploader) - INFO - Iteration through files over.
14:30:46 INFO - Return code: 0
14:30:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
14:30:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
14:30:46 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7370763b504b1abce620a5f43f06f10ddcccdb41d74c288364f0ba423658ccf99e453447fc22a73aea13291d54ff2fb0e543057c989a4f2c334b4172d1d066b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c965d119e7b22cf5eaf9664878109fa1553cbf818921ce90ce6ae9956955dd5310418dbb785e89b7f40a138d9bdb23c855a5b8d86488f3495156ead5d5c51bf", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940c5bf1538db84a5fa6cb64ec232830dfb060e9a3d79b459d0a92e2aeef74fa343cb9625f32fa23647a4cf463da2e1232370f1b7d19d343f5439e9bf1106790", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d827d474f57a1bbd2a7228fdddf9ba080726ec500a378f0853554d28989ed21c5dd452b12153cd6bc0490c7278d621305dd89765e6df9cd3255aff6537e49ab"}
14:30:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
14:30:46 INFO - Writing to file /builds/slave/test/properties/blobber_files
14:30:46 INFO - Contents:
14:30:46 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7370763b504b1abce620a5f43f06f10ddcccdb41d74c288364f0ba423658ccf99e453447fc22a73aea13291d54ff2fb0e543057c989a4f2c334b4172d1d066b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c965d119e7b22cf5eaf9664878109fa1553cbf818921ce90ce6ae9956955dd5310418dbb785e89b7f40a138d9bdb23c855a5b8d86488f3495156ead5d5c51bf", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940c5bf1538db84a5fa6cb64ec232830dfb060e9a3d79b459d0a92e2aeef74fa343cb9625f32fa23647a4cf463da2e1232370f1b7d19d343f5439e9bf1106790", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d827d474f57a1bbd2a7228fdddf9ba080726ec500a378f0853554d28989ed21c5dd452b12153cd6bc0490c7278d621305dd89765e6df9cd3255aff6537e49ab"}
14:30:46 INFO - Running post-run listener: copy_logs_to_upload_dir
14:30:46 INFO - Copying logs to upload dir...
14:30:46 INFO - mkdir: /builds/slave/test/build/upload/logs
14:30:46 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=726.847536
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 12 mins, 6 secs) (at 2017-11-25 14:30:46.396326) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-25 14:30:46.400014) =========
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=5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336
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-Non-PGO/sha512/a7370763b504b1abce620a5f43f06f10ddcccdb41d74c288364f0ba423658ccf99e453447fc22a73aea13291d54ff2fb0e543057c989a4f2c334b4172d1d066b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c965d119e7b22cf5eaf9664878109fa1553cbf818921ce90ce6ae9956955dd5310418dbb785e89b7f40a138d9bdb23c855a5b8d86488f3495156ead5d5c51bf", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940c5bf1538db84a5fa6cb64ec232830dfb060e9a3d79b459d0a92e2aeef74fa343cb9625f32fa23647a4cf463da2e1232370f1b7d19d343f5439e9bf1106790", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d827d474f57a1bbd2a7228fdddf9ba080726ec500a378f0853554d28989ed21c5dd452b12153cd6bc0490c7278d621305dd89765e6df9cd3255aff6537e49ab"}
build_url:https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011313
build_url: 'https://queue.taskcluster.net/v1/task/fy9Hgj18RxSDrfWVwwbQpQ/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a7370763b504b1abce620a5f43f06f10ddcccdb41d74c288364f0ba423658ccf99e453447fc22a73aea13291d54ff2fb0e543057c989a4f2c334b4172d1d066b", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c965d119e7b22cf5eaf9664878109fa1553cbf818921ce90ce6ae9956955dd5310418dbb785e89b7f40a138d9bdb23c855a5b8d86488f3495156ead5d5c51bf", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/940c5bf1538db84a5fa6cb64ec232830dfb060e9a3d79b459d0a92e2aeef74fa343cb9625f32fa23647a4cf463da2e1232370f1b7d19d343f5439e9bf1106790", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7d827d474f57a1bbd2a7228fdddf9ba080726ec500a378f0853554d28989ed21c5dd452b12153cd6bc0490c7278d621305dd89765e6df9cd3255aff6537e49ab"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-25 14:30:46.429501) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 14:30:46.429807) =========
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=5cf47e208febbcd81da23b2e0000026f-1511648316.164183-935949336
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004594
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-25 14:30:46.512549) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-25 14:30:46.512873) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-25 14:30:46.513153) =========
========= Total master_lag: 0.20 =========