builder: mozilla-central_ubuntu64_hw_qr_test-g4-e10s
slave: talos-linux64-ix-083
starttime: 1511089001.07
results: success (0)
revision: f6a49a33c53dd7c515b39c1a81a6330a0030bdaa
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.066300) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.066732) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.067048) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.086487) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.086789) =========
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=3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-19 02:56:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 221M=0s
2017-11-19 02:56:41 (221 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.326627
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.452741) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.453049) =========
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=3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020707
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:41.503155) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-19 02:56:41.503495) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f6a49a33c53dd7c515b39c1a81a6330a0030bdaa --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 f6a49a33c53dd7c515b39c1a81a6330a0030bdaa --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=3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-19 02:56:41,553 truncating revision to first 12 chars
2017-11-19 02:56:41,553 Setting DEBUG logging.
2017-11-19 02:56:41,553 attempt 1/10
2017-11-19 02:56:41,553 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f6a49a33c53d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-19 02:56:41,928 unpacking tar archive at: mozilla-central-f6a49a33c53d/testing/mozharness/
program finished with exit code 0
elapsedTime=1.022974
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-19 02:56:42.546770) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:42.547052) =========
script_repo_revision: f6a49a33c53dd7c515b39c1a81a6330a0030bdaa
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:42.550450) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:42.550729) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-19 02:56:42.571406) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 42 secs) (at 2017-11-19 02:56:42.571865) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
02:56:42 INFO - MultiFileLogger online at 20171119 02:56:42 in /builds/slave/test
02:56:42 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
02:56:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
02:56:42 INFO - {'append_to_log': False,
02:56:42 INFO - 'base_work_dir': '/builds/slave/test',
02:56:42 INFO - 'blob_upload_branch': 'Firefox',
02:56:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
02:56:42 INFO - 'branch': 'Firefox',
02:56:42 INFO - 'buildbot_json_path': 'buildprops.json',
02:56:42 INFO - 'code_coverage': False,
02:56:42 INFO - 'config_files': ('talos/linux_config.py',),
02:56:42 INFO - 'default_actions': ('clobber',
02:56:42 INFO - 'read-buildbot-config',
02:56:42 INFO - 'download-and-extract',
02:56:42 INFO - 'populate-webroot',
02:56:42 INFO - 'create-virtualenv',
02:56:42 INFO - 'install',
02:56:42 INFO - 'setup-mitmproxy',
02:56:42 INFO - 'run-tests'),
02:56:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
02:56:42 INFO - 'disable_ccov_upload': False,
02:56:42 INFO - 'disable_stylo': False,
02:56:42 INFO - 'download_minidump_stackwalk': True,
02:56:42 INFO - 'download_symbols': 'ondemand',
02:56:42 INFO - 'e10s': False,
02:56:42 INFO - 'enable_stylo': False,
02:56:42 INFO - 'enable_webrender': False,
02:56:42 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
02:56:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
02:56:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
02:56:42 INFO - 'gecko_profile': False,
02:56:42 INFO - 'gecko_profile_interval': 0,
02:56:42 INFO - 'installer_path': 'installer.exe',
02:56:42 INFO - 'jsd_code_coverage': False,
02:56:42 INFO - 'log_level': 'info',
02:56:42 INFO - 'log_name': 'talos',
02:56:42 INFO - 'log_to_console': True,
02:56:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
02:56:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
02:56:42 INFO - 'opt_config_files': (),
02:56:42 INFO - 'pip_index': False,
02:56:42 INFO - 'suite': 'g4-e10s',
02:56:42 INFO - 'system_bits': '32',
02:56:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
02:56:42 INFO - 'title': 'talos-linux64-ix-083',
02:56:42 INFO - 'tooltool_cache': '/builds/tooltool_cache',
02:56:42 INFO - 'use_talos_json': True,
02:56:42 INFO - 'verify': 'False',
02:56:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
02:56:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
02:56:42 INFO - 'work_dir': 'build'}
02:56:42 INFO - [mozharness: 2017-11-19 10:56:42.757541Z] Running clobber step.
02:56:42 INFO - Running pre-action listener: _resource_record_pre_action
02:56:42 INFO - Running main action method: clobber
02:56:42 INFO - rmtree: /builds/slave/test/build
02:56:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
02:56:43 INFO - Running post-action listener: _resource_record_post_action
02:56:43 INFO - [mozharness: 2017-11-19 10:56:43.848951Z] Finished clobber step (success)
02:56:43 INFO - [mozharness: 2017-11-19 10:56:43.849045Z] Running read-buildbot-config step.
02:56:43 INFO - Running pre-action listener: _resource_record_pre_action
02:56:43 INFO - Running main action method: read_buildbot_config
02:56:43 INFO - Using buildbot properties:
02:56:43 INFO - {
02:56:43 INFO - "project": "",
02:56:43 INFO - "product": "firefox",
02:56:43 INFO - "who": "aiakab@mozilla.com",
02:56:43 INFO - "installer_path": "public/build/target.tar.bz2",
02:56:43 INFO - "builddir": "mozilla-central_ubuntu64_hw_qr_test-g4-e10s",
02:56:43 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
02:56:43 INFO - "buildername": "Ubuntu HW 12.04 x64 qr mozilla-central talos g4-e10s",
02:56:43 INFO - "stage_platform": "linux64-qr",
02:56:43 INFO - "basedir": "/builds/slave/test",
02:56:43 INFO - "buildnumber": 213,
02:56:43 INFO - "platform": "ubuntu64_hw_qr",
02:56:43 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
02:56:43 INFO - "slavebuilddir": "test",
02:56:43 INFO - "taskId": "G77PpuPmQtGRmvkvlymH3g",
02:56:43 INFO - "branch": "mozilla-central",
02:56:43 INFO - "script_repo_revision": "production",
02:56:43 INFO - "revision": "f6a49a33c53dd7c515b39c1a81a6330a0030bdaa",
02:56:43 INFO - "slavename": "talos-linux64-ix-083",
02:56:43 INFO - "repo_path": "mozilla-central"
02:56:43 INFO - }
02:56:43 INFO - Finding installer, test and symbols from parent task.
02:56:43 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G77PpuPmQtGRmvkvlymH3g'}, attempt #1
02:56:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G77PpuPmQtGRmvkvlymH3g'}, attempt #1
02:56:44 INFO - Task dependencies: MXl7ZxliSaeQmdtSsZoFEg
02:56:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg'}, attempt #1
02:56:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2
02:56:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.test_packages.json
02:56:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.crashreporter-symbols.zip
02:56:45 INFO - Running post-action listener: _resource_record_post_action
02:56:45 INFO - [mozharness: 2017-11-19 10:56:45.194402Z] Finished read-buildbot-config step (success)
02:56:45 INFO - [mozharness: 2017-11-19 10:56:45.194619Z] Running download-and-extract step.
02:56:45 INFO - Running pre-action listener: _resource_record_pre_action
02:56:45 INFO - Running main action method: download_and_extract
02:56:45 INFO - mkdir: /builds/slave/test/build/tests
02:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:56:45 INFO - trying https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.test_packages.json
02:56:45 INFO - Downloading https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
02:56:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
02:56:45 INFO - Downloaded 989 bytes.
02:56:45 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
02:56:45 INFO - Using the following test package requirements:
02:56:45 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
02:56:45 INFO - u'common': [u'target.common.tests.zip'],
02:56:45 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
02:56:45 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
02:56:45 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
02:56:45 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
02:56:45 INFO - u'mozbase': [u'target.common.tests.zip'],
02:56:45 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
02:56:45 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
02:56:45 INFO - u'web-platform': [u'target.common.tests.zip',
02:56:45 INFO - u'target.web-platform.tests.tar.gz'],
02:56:45 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
02:56:45 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
02:56:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.common.tests.zip
02:56:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.common.tests.zip'}, attempt #1
02:56:45 INFO - Fetch https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.common.tests.zip into memory
02:56:47 INFO - Content-Length response header: 59053564
02:56:47 INFO - Bytes received: 59053564
02:56:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.talos.tests.zip
02:56:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.talos.tests.zip'}, attempt #1
02:56:58 INFO - Fetch https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.talos.tests.zip into memory
02:56:59 INFO - Content-Length response header: 18771853
02:56:59 INFO - Bytes received: 18771853
02:56:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:56:59 INFO - trying https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2
02:56:59 INFO - Downloading https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
02:56:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
02:57:01 INFO - Downloaded 62435693 bytes.
02:57:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2
02:57:01 INFO - mkdir: /builds/slave/test/properties
02:57:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
02:57:01 INFO - Writing to file /builds/slave/test/properties/build_url
02:57:01 INFO - Contents:
02:57:01 INFO - build_url:https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2
02:57:01 INFO - Running post-action listener: _resource_record_post_action
02:57:01 INFO - Running post-action listener: find_tests_for_verification
02:57:01 INFO - Running post-action listener: set_extra_try_arguments
02:57:01 INFO - [mozharness: 2017-11-19 10:57:01.329208Z] Finished download-and-extract step (success)
02:57:01 INFO - [mozharness: 2017-11-19 10:57:01.329314Z] Running populate-webroot step.
02:57:01 INFO - Running pre-action listener: _resource_record_pre_action
02:57:01 INFO - Running main action method: populate_webroot
02:57:01 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
02:57:01 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['tresize',
02:57:01 INFO - 'tcanvasmark']},
02:57:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
02:57:01 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['dromaeo_css',
02:57:01 INFO - 'kraken']},
02:57:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:57:01 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
02:57:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'tests': ['damp', 'tps']},
02:57:01 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['damp', 'tps']},
02:57:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
02:57:01 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['dromaeo_dom']},
02:57:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
02:57:01 INFO - 'glvideo',
02:57:01 INFO - 'displaylist_mutate']},
02:57:01 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['basic_compositor_video',
02:57:01 INFO - 'glvideo']},
02:57:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
02:57:01 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['ts_paint_webext',
02:57:01 INFO - 'tp5o_webext']},
02:57:01 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
02:57:01 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:57:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:57:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:57:01 INFO - 'talos_options': ['--mitmproxy',
02:57:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:57:01 INFO - '--firstNonBlankPaint'],
02:57:01 INFO - 'tests': ['tp6_google_heavy',
02:57:01 INFO - 'tp6_youtube_heavy',
02:57:01 INFO - 'tp6_amazon_heavy',
02:57:01 INFO - 'tp6_facebook_heavy']},
02:57:01 INFO - 'other-e10s': {'tests': ['a11yr',
02:57:01 INFO - 'ts_paint',
02:57:01 INFO - 'tpaint',
02:57:01 INFO - 'sessionrestore',
02:57:01 INFO - 'sessionrestore_many_windows',
02:57:01 INFO - 'sessionrestore_no_auto_restore',
02:57:01 INFO - 'tabpaint',
02:57:01 INFO - 'cpstartup']},
02:57:01 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['a11yr',
02:57:01 INFO - 'ts_paint',
02:57:01 INFO - 'tpaint',
02:57:01 INFO - 'sessionrestore',
02:57:01 INFO - 'sessionrestore_many_windows',
02:57:01 INFO - 'sessionrestore_no_auto_restore',
02:57:01 INFO - 'tabpaint',
02:57:01 INFO - 'cpstartup']},
02:57:01 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
02:57:01 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
02:57:01 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['perf_reftest_singletons']},
02:57:01 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['perf_reftest']},
02:57:01 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
02:57:01 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['speedometer']},
02:57:01 INFO - 'svgr-e10s': {'tests': ['tsvgx',
02:57:01 INFO - 'tsvgr_opacity',
02:57:01 INFO - 'tart',
02:57:01 INFO - 'tscrollx',
02:57:01 INFO - 'tsvg_static']},
02:57:01 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['tsvgx',
02:57:01 INFO - 'tsvgr_opacity',
02:57:01 INFO - 'tart',
02:57:01 INFO - 'tscrollx',
02:57:01 INFO - 'tsvg_static']},
02:57:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
02:57:01 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'talos_options': ['--disable-stylo'],
02:57:01 INFO - 'tests': ['tp5o']},
02:57:01 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:57:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:57:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:57:01 INFO - 'talos_options': ['--mitmproxy',
02:57:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:57:01 INFO - '--firstNonBlankPaint'],
02:57:01 INFO - 'tests': ['tp6_google',
02:57:01 INFO - 'tp6_youtube',
02:57:01 INFO - 'tp6_amazon',
02:57:01 INFO - 'tp6_facebook']},
02:57:01 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:57:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:57:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:57:01 INFO - 'talos_options': ['--disable-stylo',
02:57:01 INFO - '--mitmproxy',
02:57:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:57:01 INFO - '--firstNonBlankPaint'],
02:57:01 INFO - 'tests': ['tp6_google',
02:57:01 INFO - 'tp6_youtube',
02:57:01 INFO - 'tp6_amazon',
02:57:01 INFO - 'tp6_facebook']},
02:57:01 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
02:57:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
02:57:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
02:57:01 INFO - 'talos_options': ['--stylo-threads=1',
02:57:01 INFO - '--mitmproxy',
02:57:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
02:57:01 INFO - '--firstNonBlankPaint'],
02:57:01 INFO - 'tests': ['tp6_google',
02:57:01 INFO - 'tp6_youtube',
02:57:01 INFO - 'tp6_amazon',
02:57:01 INFO - 'tp6_facebook']},
02:57:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'talos_options': ['--xperf_path',
02:57:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:57:01 INFO - 'tests': ['tp5n']},
02:57:01 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
02:57:01 INFO - 'talos_options': ['--disable-stylo',
02:57:01 INFO - '--xperf_path',
02:57:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
02:57:01 INFO - 'tests': ['tp5n']}}}
02:57:01 INFO - Running post-action listener: _resource_record_post_action
02:57:01 INFO - [mozharness: 2017-11-19 10:57:01.349211Z] Finished populate-webroot step (success)
02:57:01 INFO - [mozharness: 2017-11-19 10:57:01.349320Z] Running create-virtualenv step.
02:57:01 INFO - Running pre-action listener: _resource_record_pre_action
02:57:01 INFO - Running main action method: create_virtualenv
02:57:01 INFO - Creating virtualenv /builds/slave/test/build/venv
02:57:01 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
02:57:01 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
02:57:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
02:57:01 INFO - Using real prefix '/usr'
02:57:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python
02:57:01 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
02:57:04 INFO - Installing setuptools, pip, wheel...done.
02:57:04 INFO - Return code: 0
02:57:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
02:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:04 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
02:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
02:57:04 INFO - Using env: {'DISPLAY': ':0',
02:57:04 INFO - 'HOME': '/home/cltbld',
02:57:04 INFO - 'LANG': 'en_US.UTF-8',
02:57:04 INFO - 'LANGUAGE': 'en_US:en',
02:57:04 INFO - 'LOGNAME': 'cltbld',
02:57:04 INFO - 'MAIL': '/var/mail/cltbld',
02:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:04 INFO - 'MOZ_NO_REMOTE': '1',
02:57:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:04 INFO - 'NO_EM_RESTART': '1',
02:57:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:04 INFO - 'PWD': '/builds/slave/test',
02:57:04 INFO - 'SHELL': '/bin/bash',
02:57:04 INFO - 'SHLVL': '1',
02:57:04 INFO - 'TERM': 'linux',
02:57:04 INFO - 'TMOUT': '86400',
02:57:04 INFO - 'USER': 'cltbld',
02:57:04 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:04 INFO - '_': '/tools/buildbot/bin/python'}
02:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
02:57:06 INFO - Return code: 0
02:57:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
02:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
02:57:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
02:57:06 INFO - Using env: {'DISPLAY': ':0',
02:57:06 INFO - 'HOME': '/home/cltbld',
02:57:06 INFO - 'LANG': 'en_US.UTF-8',
02:57:06 INFO - 'LANGUAGE': 'en_US:en',
02:57:06 INFO - 'LOGNAME': 'cltbld',
02:57:06 INFO - 'MAIL': '/var/mail/cltbld',
02:57:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:06 INFO - 'MOZ_NO_REMOTE': '1',
02:57:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:06 INFO - 'NO_EM_RESTART': '1',
02:57:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:06 INFO - 'PWD': '/builds/slave/test',
02:57:06 INFO - 'SHELL': '/bin/bash',
02:57:06 INFO - 'SHLVL': '1',
02:57:06 INFO - 'TERM': 'linux',
02:57:06 INFO - 'TMOUT': '86400',
02:57:06 INFO - 'USER': 'cltbld',
02:57:06 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:06 INFO - '_': '/tools/buildbot/bin/python'}
02:57:06 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:06 INFO - Collecting psutil>=3.1.1
02:57:07 INFO - Installing collected packages: psutil
02:57:07 INFO - Successfully installed psutil-3.1.1
02:57:07 INFO - Return code: 0
02:57:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
02:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:07 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
02:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
02:57:07 INFO - Using env: {'DISPLAY': ':0',
02:57:07 INFO - 'HOME': '/home/cltbld',
02:57:07 INFO - 'LANG': 'en_US.UTF-8',
02:57:07 INFO - 'LANGUAGE': 'en_US:en',
02:57:07 INFO - 'LOGNAME': 'cltbld',
02:57:07 INFO - 'MAIL': '/var/mail/cltbld',
02:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:07 INFO - 'MOZ_NO_REMOTE': '1',
02:57:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:07 INFO - 'NO_EM_RESTART': '1',
02:57:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:07 INFO - 'PWD': '/builds/slave/test',
02:57:07 INFO - 'SHELL': '/bin/bash',
02:57:07 INFO - 'SHLVL': '1',
02:57:07 INFO - 'TERM': 'linux',
02:57:07 INFO - 'TMOUT': '86400',
02:57:07 INFO - 'USER': 'cltbld',
02:57:07 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:07 INFO - '_': '/tools/buildbot/bin/python'}
02:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:08 INFO - Collecting mozsystemmonitor==0.3
02:57:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
02:57:09 INFO - Installing collected packages: mozsystemmonitor
02:57:09 INFO - Successfully installed mozsystemmonitor-0.3
02:57:09 INFO - Return code: 0
02:57:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
02:57: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')]}
02:57:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57: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')]}
02:57:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57: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', '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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57: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', 'jsonschema==2.5.1'] in /builds/slave/test/build
02:57: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 jsonschema==2.5.1
02:57:09 INFO - Using env: {'DISPLAY': ':0',
02:57:09 INFO - 'HOME': '/home/cltbld',
02:57:09 INFO - 'LANG': 'en_US.UTF-8',
02:57:09 INFO - 'LANGUAGE': 'en_US:en',
02:57:09 INFO - 'LOGNAME': 'cltbld',
02:57:09 INFO - 'MAIL': '/var/mail/cltbld',
02:57:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:09 INFO - 'MOZ_NO_REMOTE': '1',
02:57:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:09 INFO - 'NO_EM_RESTART': '1',
02:57:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:09 INFO - 'PWD': '/builds/slave/test',
02:57:09 INFO - 'SHELL': '/bin/bash',
02:57:09 INFO - 'SHLVL': '1',
02:57:09 INFO - 'TERM': 'linux',
02:57:09 INFO - 'TMOUT': '86400',
02:57:09 INFO - 'USER': 'cltbld',
02:57:09 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:09 INFO - '_': '/tools/buildbot/bin/python'}
02:57:09 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:09 INFO - Collecting jsonschema==2.5.1
02:57:10 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
02:57:10 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
02:57:11 INFO - Installing collected packages: functools32, jsonschema
02:57:11 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
02:57:11 INFO - Return code: 0
02:57:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
02:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:11 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
02:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
02:57:11 INFO - Using env: {'DISPLAY': ':0',
02:57:11 INFO - 'HOME': '/home/cltbld',
02:57:11 INFO - 'LANG': 'en_US.UTF-8',
02:57:11 INFO - 'LANGUAGE': 'en_US:en',
02:57:11 INFO - 'LOGNAME': 'cltbld',
02:57:11 INFO - 'MAIL': '/var/mail/cltbld',
02:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:11 INFO - 'MOZ_NO_REMOTE': '1',
02:57:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:11 INFO - 'NO_EM_RESTART': '1',
02:57:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:11 INFO - 'PWD': '/builds/slave/test',
02:57:11 INFO - 'SHELL': '/bin/bash',
02:57:11 INFO - 'SHLVL': '1',
02:57:11 INFO - 'TERM': 'linux',
02:57:11 INFO - 'TMOUT': '86400',
02:57:11 INFO - 'USER': 'cltbld',
02:57:11 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:11 INFO - '_': '/tools/buildbot/bin/python'}
02:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
02:57:12 INFO - Return code: 0
02:57:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
02:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:12 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
02:57:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
02:57:12 INFO - Using env: {'DISPLAY': ':0',
02:57:12 INFO - 'HOME': '/home/cltbld',
02:57:12 INFO - 'LANG': 'en_US.UTF-8',
02:57:12 INFO - 'LANGUAGE': 'en_US:en',
02:57:12 INFO - 'LOGNAME': 'cltbld',
02:57:12 INFO - 'MAIL': '/var/mail/cltbld',
02:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:12 INFO - 'MOZ_NO_REMOTE': '1',
02:57:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:12 INFO - 'NO_EM_RESTART': '1',
02:57:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:12 INFO - 'PWD': '/builds/slave/test',
02:57:12 INFO - 'SHELL': '/bin/bash',
02:57:12 INFO - 'SHLVL': '1',
02:57:12 INFO - 'TERM': 'linux',
02:57:12 INFO - 'TMOUT': '86400',
02:57:12 INFO - 'USER': 'cltbld',
02:57:12 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:12 INFO - '_': '/tools/buildbot/bin/python'}
02:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:12 INFO - Collecting blobuploader==1.2.4
02:57:13 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
02:57:14 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
02:57:15 INFO - Installing collected packages: requests, docopt, blobuploader
02:57:16 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
02:57:16 INFO - Return code: 0
02:57:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:16 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
02:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:57:16 INFO - Using env: {'DISPLAY': ':0',
02:57:16 INFO - 'HOME': '/home/cltbld',
02:57:16 INFO - 'LANG': 'en_US.UTF-8',
02:57:16 INFO - 'LANGUAGE': 'en_US:en',
02:57:16 INFO - 'LOGNAME': 'cltbld',
02:57:16 INFO - 'MAIL': '/var/mail/cltbld',
02:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:16 INFO - 'MOZ_NO_REMOTE': '1',
02:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:16 INFO - 'NO_EM_RESTART': '1',
02:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:16 INFO - 'PWD': '/builds/slave/test',
02:57:16 INFO - 'SHELL': '/bin/bash',
02:57:16 INFO - 'SHLVL': '1',
02:57:16 INFO - 'TERM': 'linux',
02:57:16 INFO - 'TMOUT': '86400',
02:57:16 INFO - 'USER': 'cltbld',
02:57:16 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:16 INFO - '_': '/tools/buildbot/bin/python'}
02:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:16 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:57:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:57:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:57:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:57:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:57:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:57:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:57:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:57:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:57:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:57:18 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:57:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:57:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:57:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:57:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:57:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:57:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:57:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
02:57:19 INFO - Running setup.py install for manifestparser: started
02:57:19 INFO - Running setup.py install for manifestparser: finished with status 'done'
02:57:19 INFO - Running setup.py install for mozcrash: started
02:57:20 INFO - Running setup.py install for mozcrash: finished with status 'done'
02:57:20 INFO - Running setup.py install for mozdebug: started
02:57:20 INFO - Running setup.py install for mozdebug: finished with status 'done'
02:57:20 INFO - Running setup.py install for mozdevice: started
02:57:20 INFO - Running setup.py install for mozdevice: finished with status 'done'
02:57:20 INFO - Running setup.py install for mozfile: started
02:57:20 INFO - Running setup.py install for mozfile: finished with status 'done'
02:57:20 INFO - Running setup.py install for mozhttpd: started
02:57:21 INFO - Running setup.py install for mozhttpd: finished with status 'done'
02:57:21 INFO - Running setup.py install for mozinfo: started
02:57:21 INFO - Running setup.py install for mozinfo: finished with status 'done'
02:57:21 INFO - Running setup.py install for mozInstall: started
02:57:21 INFO - Running setup.py install for mozInstall: finished with status 'done'
02:57:21 INFO - Running setup.py install for mozleak: started
02:57:21 INFO - Running setup.py install for mozleak: finished with status 'done'
02:57:21 INFO - Running setup.py install for mozlog: started
02:57:21 INFO - Running setup.py install for mozlog: finished with status 'done'
02:57:21 INFO - Running setup.py install for moznetwork: started
02:57:22 INFO - Running setup.py install for moznetwork: finished with status 'done'
02:57:22 INFO - Running setup.py install for mozprocess: started
02:57:22 INFO - Running setup.py install for mozprocess: finished with status 'done'
02:57:22 INFO - Running setup.py install for mozprofile: started
02:57:22 INFO - Running setup.py install for mozprofile: finished with status 'done'
02:57:22 INFO - Running setup.py install for mozrunner: started
02:57:22 INFO - Running setup.py install for mozrunner: finished with status 'done'
02:57:22 INFO - Running setup.py install for mozscreenshot: started
02:57:23 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
02:57:23 INFO - Running setup.py install for moztest: started
02:57:23 INFO - Running setup.py install for moztest: finished with status 'done'
02:57:23 INFO - Running setup.py install for mozversion: started
02:57:23 INFO - Running setup.py install for mozversion: finished with status 'done'
02:57:23 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
02:57:23 INFO - Return code: 0
02:57:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:23 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
02:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:57:23 INFO - Using env: {'DISPLAY': ':0',
02:57:23 INFO - 'HOME': '/home/cltbld',
02:57:23 INFO - 'LANG': 'en_US.UTF-8',
02:57:23 INFO - 'LANGUAGE': 'en_US:en',
02:57:23 INFO - 'LOGNAME': 'cltbld',
02:57:23 INFO - 'MAIL': '/var/mail/cltbld',
02:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:23 INFO - 'MOZ_NO_REMOTE': '1',
02:57:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:23 INFO - 'NO_EM_RESTART': '1',
02:57:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:23 INFO - 'PWD': '/builds/slave/test',
02:57:23 INFO - 'SHELL': '/bin/bash',
02:57:23 INFO - 'SHLVL': '1',
02:57:23 INFO - 'TERM': 'linux',
02:57:23 INFO - 'TMOUT': '86400',
02:57:23 INFO - 'USER': 'cltbld',
02:57:23 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:23 INFO - '_': '/tools/buildbot/bin/python'}
02:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:24 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:57:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:57:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
02:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
02:57:24 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:57:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
02:57:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
02:57:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
02:57:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
02:57:25 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
02:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
02:57:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
02:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:57:26 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
02:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
02:57:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
02:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
02:57:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
02:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
02:57:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
02:57:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
02:57:27 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
02:57:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
02:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
02:57:27 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
02:57:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
02:57:28 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
02:57:28 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
02:57:29 INFO - Installing collected packages: six, blessings
02:57:30 INFO - Successfully installed blessings-1.6 six-1.10.0
02:57:30 INFO - Return code: 0
02:57:30 INFO - Done creating virtualenv /builds/slave/test/build/venv.
02:57:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:57:30 INFO - Reading from file tmpfile_stdout
02:57:30 INFO - Reading from file tmpfile_stderr
02:57:30 INFO - Current package versions:
02:57:30 INFO - blessings == 1.6
02:57:30 INFO - blobuploader == 1.2.4
02:57:30 INFO - docopt == 0.6.1
02:57:30 INFO - functools32 == 3.2.3.post2
02:57:30 INFO - jsonschema == 2.5.1
02:57:30 INFO - manifestparser == 1.2
02:57:30 INFO - mozInstall == 1.14
02:57:30 INFO - mozcrash == 1.0
02:57:30 INFO - mozdebug == 0.1
02:57:30 INFO - mozdevice == 0.51
02:57:30 INFO - mozfile == 1.2
02:57:30 INFO - mozhttpd == 0.7
02:57:30 INFO - mozinfo == 0.10
02:57:30 INFO - mozleak == 0.1
02:57:30 INFO - mozlog == 3.5
02:57:30 INFO - moznetwork == 0.27
02:57:30 INFO - mozprocess == 0.25
02:57:30 INFO - mozprofile == 0.29
02:57:30 INFO - mozrunner == 6.13
02:57:30 INFO - mozscreenshot == 0.1
02:57:30 INFO - mozsystemmonitor == 0.3
02:57:30 INFO - moztest == 0.8
02:57:30 INFO - mozversion == 1.4
02:57:30 INFO - psutil == 3.1.1
02:57:30 INFO - requests == 1.2.3
02:57:30 INFO - six == 1.10.0
02:57:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv
02:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:30 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
02:57:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
02:57:30 INFO - Using env: {'DISPLAY': ':0',
02:57:30 INFO - 'HOME': '/home/cltbld',
02:57:30 INFO - 'LANG': 'en_US.UTF-8',
02:57:30 INFO - 'LANGUAGE': 'en_US:en',
02:57:30 INFO - 'LOGNAME': 'cltbld',
02:57:30 INFO - 'MAIL': '/var/mail/cltbld',
02:57:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:30 INFO - 'MOZ_NO_REMOTE': '1',
02:57:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:30 INFO - 'NO_EM_RESTART': '1',
02:57:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:30 INFO - 'PWD': '/builds/slave/test',
02:57:30 INFO - 'SHELL': '/bin/bash',
02:57:30 INFO - 'SHLVL': '1',
02:57:30 INFO - 'TERM': 'linux',
02:57:30 INFO - 'TMOUT': '86400',
02:57:30 INFO - 'USER': 'cltbld',
02:57:30 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:30 INFO - '_': '/tools/buildbot/bin/python'}
02:57:30 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
02:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
02:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
02:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
02:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
02:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
02:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
02:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
02:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
02:57:31 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
02:57:32 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:57:33 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
02:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
02:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
02:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
02:57:33 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:57:34 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
02:57:34 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:57:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
02:57:35 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:57:36 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
02:57:36 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
02:57:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
02:57:37 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
02:57:37 INFO - Found existing installation: requests 1.2.3
02:57:37 INFO - Uninstalling requests-1.2.3:
02:57:37 INFO - Successfully uninstalled requests-1.2.3
02:57:37 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
02:57:37 INFO - Return code: 0
02:57:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
02:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
02:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
02:57:37 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 0x1203d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11e2a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13841a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, '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': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
02:57:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
02:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
02:57:37 INFO - Using env: {'DISPLAY': ':0',
02:57:37 INFO - 'HOME': '/home/cltbld',
02:57:37 INFO - 'LANG': 'en_US.UTF-8',
02:57:37 INFO - 'LANGUAGE': 'en_US:en',
02:57:37 INFO - 'LOGNAME': 'cltbld',
02:57:37 INFO - 'MAIL': '/var/mail/cltbld',
02:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:37 INFO - 'MOZ_NO_REMOTE': '1',
02:57:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:37 INFO - 'NO_EM_RESTART': '1',
02:57:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:37 INFO - 'PWD': '/builds/slave/test',
02:57:37 INFO - 'SHELL': '/bin/bash',
02:57:37 INFO - 'SHLVL': '1',
02:57:37 INFO - 'TERM': 'linux',
02:57:37 INFO - 'TMOUT': '86400',
02:57:37 INFO - 'USER': 'cltbld',
02:57:37 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:37 INFO - '_': '/tools/buildbot/bin/python'}
02:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple
02:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
02:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
02:57:38 INFO - Return code: 0
02:57:38 INFO - Running post-action listener: _resource_record_post_action
02:57:38 INFO - Running post-action listener: _start_resource_monitoring
02:57:38 INFO - Starting resource monitoring.
02:57:38 INFO - [mozharness: 2017-11-19 10:57:38.341640Z] Finished create-virtualenv step (success)
02:57:38 INFO - [mozharness: 2017-11-19 10:57:38.342154Z] Running install step.
02:57:38 INFO - Running pre-action listener: _resource_record_pre_action
02:57:38 INFO - Running main action method: install
02:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
02:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
02:57:38 INFO - Reading from file tmpfile_stdout
02:57:38 INFO - Reading from file tmpfile_stderr
02:57:38 INFO - Detecting whether we're running mozinstall >=1.0...
02:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
02:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
02:57:39 INFO - Reading from file tmpfile_stdout
02:57:39 INFO - Output received:
02:57:39 INFO - Usage: mozinstall [options] installer
02:57:39 INFO - Options:
02:57:39 INFO - -h, --help show this help message and exit
02:57:39 INFO - -d DEST, --destination=DEST
02:57:39 INFO - Directory to install application into. [default:
02:57:39 INFO - "/builds/slave/test"]
02:57:39 INFO - --app=APP Application being installed. [default: firefox]
02:57:39 INFO - mkdir: /builds/slave/test/build/application
02:57:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
02:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
02:57:57 INFO - Reading from file tmpfile_stdout
02:57:57 INFO - Output received:
02:57:57 INFO - /builds/slave/test/build/application/firefox/firefox
02:57:57 INFO - Running post-action listener: _resource_record_post_action
02:57:57 INFO - [mozharness: 2017-11-19 10:57:57.277084Z] Finished install step (success)
02:57:57 INFO - [mozharness: 2017-11-19 10:57:57.277225Z] Running setup-mitmproxy step.
02:57:57 INFO - Running pre-action listener: _resource_record_pre_action
02:57:57 INFO - Running main action method: setup_mitmproxy
02:57:57 INFO - Skipping: mitmproxy is not required
02:57:57 INFO - Running post-action listener: _resource_record_post_action
02:57:57 INFO - [mozharness: 2017-11-19 10:57:57.277740Z] Finished setup-mitmproxy step (success)
02:57:57 INFO - [mozharness: 2017-11-19 10:57:57.277838Z] Running run-tests step.
02:57:57 INFO - Running pre-action listener: _resource_record_pre_action
02:57:57 INFO - Running pre-action listener: _set_gcov_prefix
02:57:57 INFO - Running main action method: run_tests
02:57:57 WARNING - Try message not found.
02:57:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
02:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
02:57:57 INFO - Python 2.7.3
02:57:57 INFO - Return code: 0
02:57:57 INFO - grabbing minidump binary from tooltool
02:57:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
02:57:57 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 0x1381320>, '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 0x1382220>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13826d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
02:57:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
02:57:57 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
02:57:57 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
02:57:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
02:57:57 INFO - Return code: 0
02:57:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
02:57:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
02:57:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
02:57:57 INFO - ENV: RUST_BACKTRACE is now full
02:57:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
02:57:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
02:57:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
02:57:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-083 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
02:57:57 INFO - Using env: {'DISPLAY': ':0',
02:57:57 INFO - 'HOME': '/home/cltbld',
02:57:57 INFO - 'LANG': 'en_US.UTF-8',
02:57:57 INFO - 'LANGUAGE': 'en_US:en',
02:57:57 INFO - 'LOGNAME': 'cltbld',
02:57:57 INFO - 'MAIL': '/var/mail/cltbld',
02:57:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:57:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:57:57 INFO - 'MOZ_ACCELERATED': '1',
02:57:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:57:57 INFO - 'MOZ_NO_REMOTE': '1',
02:57:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:57:57 INFO - 'MOZ_WEBRENDER': '1',
02:57:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:57:57 INFO - 'NO_EM_RESTART': '1',
02:57:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:57:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:57:57 INFO - 'PWD': '/builds/slave/test',
02:57:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:57:57 INFO - 'RUST_BACKTRACE': 'full',
02:57:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:57:57 INFO - 'SHELL': '/bin/bash',
02:57:57 INFO - 'SHLVL': '1',
02:57:57 INFO - 'TERM': 'linux',
02:57:57 INFO - 'TMOUT': '86400',
02:57:57 INFO - 'USER': 'cltbld',
02:57:57 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:57:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:57:57 INFO - '_': '/tools/buildbot/bin/python'}
02:57:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-083', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
02:57:57 INFO - mozversion application_buildid: 20171119095642
02:57:57 INFO - mozversion application_changeset: f6a49a33c53dd7c515b39c1a81a6330a0030bdaa
02:57:57 INFO - mozversion application_display_name: Nightly
02:57:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
02:57:57 INFO - mozversion application_name: Firefox
02:57:57 INFO - mozversion application_remotingname: firefox
02:57:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
02:57:57 INFO - mozversion application_vendor: Mozilla
02:57:57 INFO - mozversion application_version: 59.0a1
02:57:57 INFO - mozversion platform_buildid: 20171119095642
02:57:57 INFO - mozversion platform_changeset: f6a49a33c53dd7c515b39c1a81a6330a0030bdaa
02:57:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
02:57:57 INFO - mozversion platform_version: 59.0a1
02:57:57 INFO - using testdate: 1511089077
02:57:57 INFO - actual date: 1511089077
02:57:57 INFO - starting webserver on 'localhost:53596'
02:57:57 INFO - SUITE-START | Running 3 tests
02:57:57 INFO - TEST-START | basic_compositor_video
02:57:57 INFO - Initialising browser for basic_compositor_video test...
02:57:57 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
02:57:57 INFO - Installing Add-ons
02:57:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53596/getInfo.html -profile /tmp/tmpNeuN1M/profile
02:57:57 INFO - TEST-INFO | started process 20087 (/builds/slave/test/build/application/firefox/firefox http://localhost:53596/getInfo.html)
02:58:03 INFO - TEST-INFO | 20087: exit 0
02:58:04 INFO - Browser initialized.
02:58:04 INFO - Running cycle 1/1 for basic_compositor_video test...
02:58:04 INFO - Using env: {'DISPLAY': ':0',
02:58:04 INFO - 'HOME': '/home/cltbld',
02:58:04 INFO - 'JSGC_DISABLE_POISONING': '1',
02:58:04 INFO - 'LANG': 'en_US.UTF-8',
02:58:04 INFO - 'LANGUAGE': 'en_US:en',
02:58:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
02:58:04 INFO - 'LOGNAME': 'cltbld',
02:58:04 INFO - 'MAIL': '/var/mail/cltbld',
02:58:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
02:58:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
02:58:04 INFO - 'MOZ_ACCELERATED': '1',
02:58:04 INFO - 'MOZ_CRASHREPORTER': '1',
02:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
02:58:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
02:58:04 INFO - 'MOZ_NO_REMOTE': '1',
02:58:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
02:58:04 INFO - 'MOZ_WEBRENDER': '1',
02:58:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
02:58:04 INFO - 'NO_EM_RESTART': '1',
02:58:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
02:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
02:58:04 INFO - 'PWD': '/builds/slave/test',
02:58:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
02:58:04 INFO - 'RUST_BACKTRACE': 'full',
02:58:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
02:58:04 INFO - 'SHELL': '/bin/bash',
02:58:04 INFO - 'SHLVL': '1',
02:58:04 INFO - 'TERM': 'linux',
02:58:04 INFO - 'TMOUT': '86400',
02:58:04 INFO - 'USER': 'cltbld',
02:58:04 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
02:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
02:58:04 INFO - '_': '/tools/buildbot/bin/python'}
02:58:04 INFO - TEST-INFO | started process 20369 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNeuN1M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
02:58:04 INFO - PID 20369 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:58:05 INFO - PID 20369 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
02:58:05 INFO - PID 20369 |
02:58:05 INFO - PID 20369 | (/builds/slave/test/build/application/firefox/firefox:20431): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
02:58:05 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.508887959866221 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.454116564417178 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.515092204526404 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5009124999999996 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.086718106995886 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.06861963190184 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.191033519553076 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.132954545454543 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.552572078907436 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.599195402298854 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.695618153364633 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.68371194379391 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.026323185011713 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.782372881355942 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9937878787878764 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7798305084745865 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.044577464788728 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.78276271186441 ms/frame
02:58:51 INFO - PID 20369 |
02:58:51 INFO - PID 20369 | Cycle 1(1): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
02:59:37 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4902489626556017 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4603997539975397 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5210084033613445 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.4977278401997496 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0778256410256404 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0724884792626734 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.17867688022284 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.141335403726707 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.225739436619715 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.176252609603346 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.725220472440944 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.684519906323187 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.043779342723 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781508474576265 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9933682983683125 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.78057627118643 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.9321362586605035 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.7795593220339025 ms/frame
02:59:37 INFO - PID 20369 |
02:59:37 INFO - PID 20369 | Cycle 1(2): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
02:59:39 INFO - PID 20369 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:00:23 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.496738768718802 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4486780905752754 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.510949790794979 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5037922403003763 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0803850102669403 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0633001531393584 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.262556818181818 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.425044247787608 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.22565492957746 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.175688935281842 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.7027978056426365 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.630416666666667 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.042347417840369 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.713439597315447 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.000384615384617 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.781762711864391 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.994603729603724 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.803265306122436 ms/frame
03:00:23 INFO - PID 20369 |
03:00:23 INFO - PID 20369 | Cycle 1(3): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:01:09 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.484151490066225 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4640640394088678 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.511092050209205 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503811013767209 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0771025641025633 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0584480122324162 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.6023696319018415 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.75119952494062 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.250332861189802 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.229418604651162 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.793929712460064 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.6857025761124165 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.0118457943925225 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759966216216206 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.9771860465116164 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.8042857142857365 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.979000000000003 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.715989932885924 ms/frame
03:01:09 INFO - PID 20369 |
03:01:09 INFO - PID 20369 | Cycle 1(4): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:01:55 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4841846026490066 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460959409594096 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.511280334728033 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5064473684210533 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0746362704918027 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.072273425499231 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 5.368121645796066 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.962828784119107 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.361366279069766 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.368165938864626 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.688179687500002 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.673306074766353 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.011693925233647 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.781067796610173 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.965765661252906 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.714781879194622 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.044577464788728 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.75885135135136 ms/frame
03:01:55 INFO - PID 20369 |
03:01:55 INFO - PID 20369 | Cycle 1(5): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:02:41 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4755940594059407 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4601968019680194 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5212983193277307 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.503610763454318 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.077933333333332 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.072127496159757 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.539326777609683 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.567420091324198 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.5741387195121925 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.494977528089895 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.808469551282046 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.808461538461538 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.9776976744186126 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.7591216216216115 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.928729792147805 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.758564189189201 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.0268149882904085 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.804591836734679 ms/frame
03:02:41 INFO - PID 20369 |
03:02:41 INFO - PID 20369 | Cycle 1(6): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:03:27 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4735119538334707 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.463996305418719 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5088294314381274 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5006000000000004 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0709058341862843 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.0636140888208283 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.51181203007519 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.454331848552337 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.305487804878048 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.2108000000000025 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.688937499999997 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.652441860465115 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.010665887850473 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.805051020408155 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.010654205607471 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.828754266211619 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.009894859813069 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.75993243243244 ms/frame
03:03:27 INFO - PID 20369 |
03:03:27 INFO - PID 20369 | Cycle 1(7): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:04:13 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.482340777502068 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.464304187192118 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.521121848739496 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.49989375 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0772307692307703 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.067799079754601 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.457815750371469 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.37840262582057 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.231840620592384 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.1851778242677815 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.694757433489829 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.696690140845073 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.0100116822429905 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.827969283276454 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.077146226415092 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.7591216216216115 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 6.961531322505796 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.757533783783795 ms/frame
03:04:13 INFO - PID 20369 |
03:04:13 INFO - PID 20369 | Cycle 1(8): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:04:59 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4799586776859504 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4541472392638033 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5105146443514648 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.500581249999999 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0708597748208817 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.059013761467889 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.243946251768036 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.25523404255319 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.580954198473284 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.495617977528088 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.695500782472612 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.641090487238982 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.978883720930237 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.759020270270264 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.027529274004678 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.759966216216206 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.010023364485983 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.781288135593207 ms/frame
03:04:59 INFO - PID 20369 |
03:04:59 INFO - PID 20369 | Cycle 1(9): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:05:45 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4836382450331125 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4639162561576353 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.502714762301918 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.504292866082604 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.073862704918033 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.063866768759571 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 5.016864548494982 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.684449648711949 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.471430700447097 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.397780219780215 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.8238344051446935 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.796091127098324 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.99483682983683 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.803673469387751 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 7.011308411214945 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.759594594594607 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.04300469483568 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.762043918918927 ms/frame
03:05:45 INFO - PID 20369 |
03:05:45 INFO - PID 20369 | Cycle 1(10): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:06:31 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.476196369636964 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.4637253694581283 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.5235996635828433 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.497602996254683 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.08707304526749 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.069064417177915 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.573201219512196 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.629513888888891 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.574458841463416 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.52593891402715 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.6739563862928355 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.684344262295074 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 7.0267096018735336 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.8045578231292545 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.947002314814806 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.736111111111111 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.009521028037393 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.735084175084174 ms/frame
03:06:31 INFO - PID 20369 |
03:06:31 INFO - PID 20369 | Cycle 1(11): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:07:17 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_startup = 2.4803388429752067 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1_inclip = 2.460239852398524 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_startup = 2.513375209380235 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 2.5063533834586464 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_startup = 3.0840596094552937 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.058960244648317 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_startup = 4.367168850072784 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1_inclip = 4.330400432900426 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_startup = 4.18458856345886 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_1.1_inclip = 4.142712215320912 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_startup = 4.831521739130435 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.480p.60fps.webm_scale_2_inclip = 4.785502392344498 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_startup = 6.961287703016239 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1_inclip = 6.782627118644068 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 6.978267441860463 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 6.756993243243242 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_startup = 7.010514018691588 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | testsrc.1080p.60fps.mp4_scale_2_inclip = 6.783508474576278 ms/frame
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | Cycle 1(12): loaded http://localhost:53596/tests/video/video_playback.html (next: http://localhost:53596/tests/video/video_playback.html)
03:07:17 INFO - PID 20369 | __start_tp_report
03:07:17 INFO - PID 20369 | _x_x_mozilla_page_load
03:07:17 INFO - PID 20369 | _x_x_mozilla_page_load_details
03:07:17 INFO - PID 20369 | |i|pagename|runs|
03:07:17 INFO - PID 20369 | |0;240p.120fps.mp4_scale_1_startup;2.508887959866221;2.4902489626556017;2.496738768718802;2.484151490066225;2.4841846026490066;2.4755940594059407;2.4735119538334707;2.482340777502068;2.4799586776859504;2.4836382450331125;2.476196369636964;2.4803388429752067
03:07:17 INFO - PID 20369 | |1;240p.120fps.mp4_scale_1_inclip;2.454116564417178;2.4603997539975397;2.4486780905752754;2.4640640394088678;2.460959409594096;2.4601968019680194;2.463996305418719;2.464304187192118;2.4541472392638033;2.4639162561576353;2.4637253694581283;2.460239852398524
03:07:17 INFO - PID 20369 | |2;240p.120fps.mp4_scale_1.1_startup;2.515092204526404;2.5210084033613445;2.510949790794979;2.511092050209205;2.511280334728033;2.5212983193277307;2.5088294314381274;2.521121848739496;2.5105146443514648;2.502714762301918;2.5235996635828433;2.513375209380235
03:07:17 INFO - PID 20369 | |3;240p.120fps.mp4_scale_1.1_inclip;2.5009124999999996;2.4977278401997496;2.5037922403003763;2.503811013767209;2.5064473684210533;2.503610763454318;2.5006000000000004;2.49989375;2.500581249999999;2.504292866082604;2.497602996254683;2.5063533834586464
03:07:17 INFO - PID 20369 | |4;240p.120fps.mp4_scale_2_startup;3.086718106995886;3.0778256410256404;3.0803850102669403;3.0771025641025633;3.0746362704918027;3.077933333333332;3.0709058341862843;3.0772307692307703;3.0708597748208817;3.073862704918033;3.08707304526749;3.0840596094552937
03:07:17 INFO - PID 20369 | |5;240p.120fps.mp4_scale_2_inclip;3.06861963190184;3.0724884792626734;3.0633001531393584;3.0584480122324162;3.072273425499231;3.072127496159757;3.0636140888208283;3.067799079754601;3.059013761467889;3.063866768759571;3.069064417177915;3.058960244648317
03:07:17 INFO - PID 20369 | |6;480p.60fps.webm_scale_1_startup;4.191033519553076;4.17867688022284;4.262556818181818;4.6023696319018415;5.368121645796066;4.539326777609683;4.51181203007519;4.457815750371469;4.243946251768036;5.016864548494982;4.573201219512196;4.367168850072784
03:07:17 INFO - PID 20369 | |7;480p.60fps.webm_scale_1_inclip;4.132954545454543;4.141335403726707;4.425044247787608;4.75119952494062;4.962828784119107;4.567420091324198;4.454331848552337;4.37840262582057;4.25523404255319;4.684449648711949;4.629513888888891;4.330400432900426
03:07:17 INFO - PID 20369 | |8;480p.60fps.webm_scale_1.1_startup;4.552572078907436;4.225739436619715;4.22565492957746;4.250332861189802;4.361366279069766;4.5741387195121925;4.305487804878048;4.231840620592384;4.580954198473284;4.471430700447097;4.574458841463416;4.18458856345886
03:07:17 INFO - PID 20369 | |9;480p.60fps.webm_scale_1.1_inclip;4.599195402298854;4.176252609603346;4.175688935281842;4.229418604651162;4.368165938864626;4.494977528089895;4.2108000000000025;4.1851778242677815;4.495617977528088;4.397780219780215;4.52593891402715;4.142712215320912
03:07:17 INFO - PID 20369 | |10;480p.60fps.webm_scale_2_startup;4.695618153364633;4.725220472440944;4.7027978056426365;4.793929712460064;4.688179687500002;4.808469551282046;4.688937499999997;4.694757433489829;4.695500782472612;4.8238344051446935;4.6739563862928355;4.831521739130435
03:07:17 INFO - PID 20369 | |11;480p.60fps.webm_scale_2_inclip;4.68371194379391;4.684519906323187;4.630416666666667;4.6857025761124165;4.673306074766353;4.808461538461538;4.652441860465115;4.696690140845073;4.641090487238982;4.796091127098324;4.684344262295074;4.785502392344498
03:07:17 INFO - PID 20369 | |12;1080p.60fps.mp4_scale_1_startup;7.026323185011713;7.043779342723;7.042347417840369;7.0118457943925225;7.011693925233647;6.9776976744186126;7.010665887850473;7.0100116822429905;6.978883720930237;6.99483682983683;7.0267096018735336;6.961287703016239
03:07:17 INFO - PID 20369 | |13;1080p.60fps.mp4_scale_1_inclip;6.782372881355942;6.781508474576265;6.713439597315447;6.759966216216206;6.781067796610173;6.7591216216216115;6.805051020408155;6.827969283276454;6.759020270270264;6.803673469387751;6.8045578231292545;6.782627118644068
03:07:17 INFO - PID 20369 | |14;1080p.60fps.mp4_scale_1.1_startup;6.9937878787878764;6.9933682983683125;7.000384615384617;6.9771860465116164;6.965765661252906;6.928729792147805;7.010654205607471;7.077146226415092;7.027529274004678;7.011308411214945;6.947002314814806;6.978267441860463
03:07:17 INFO - PID 20369 | |15;1080p.60fps.mp4_scale_1.1_inclip;6.7798305084745865;6.78057627118643;6.781762711864391;6.8042857142857365;6.714781879194622;6.758564189189201;6.828754266211619;6.7591216216216115;6.759966216216206;6.759594594594607;6.736111111111111;6.756993243243242
03:07:17 INFO - PID 20369 | |16;1080p.60fps.mp4_scale_2_startup;7.044577464788728;6.9321362586605035;6.994603729603724;6.979000000000003;7.044577464788728;7.0268149882904085;7.009894859813069;6.961531322505796;7.010023364485983;7.04300469483568;7.009521028037393;7.010514018691588
03:07:17 INFO - PID 20369 | |17;1080p.60fps.mp4_scale_2_inclip;6.78276271186441;6.7795593220339025;6.803265306122436;6.715989932885924;6.75885135135136;6.804591836734679;6.75993243243244;6.757533783783795;6.781288135593207;6.762043918918927;6.735084175084174;6.783508474576278
03:07:17 INFO - PID 20369 | __end_tp_report
03:07:17 INFO - PID 20369 | __start_cc_report
03:07:17 INFO - PID 20369 | _x_x_mozilla_cycle_collect,619
03:07:17 INFO - PID 20369 | __end_cc_report
03:07:17 INFO - PID 20369 | __startTimestamp1511089637773__endTimestamp
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | ------- Summary: start -------
03:07:17 INFO - PID 20369 | Number of tests: 18
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:2.48 Median:2.48 stddev:0.01 (0.4%) stddev-sans-first:0.01
03:07:17 INFO - PID 20369 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:2.46 Median:2.46 stddev:0.01 (0.2%) stddev-sans-first:0.00
03:07:17 INFO - PID 20369 | Values: 2.5 2.5 2.4 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:2.51 Median:2.51 stddev:0.01 (0.3%) stddev-sans-first:0.01
03:07:17 INFO - PID 20369 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:2.50 Median:2.50 stddev:0.00 (0.1%) stddev-sans-first:0.00
03:07:17 INFO - PID 20369 | Values: 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5 2.5
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:3.08 Median:3.08 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:07:17 INFO - PID 20369 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:3.07 Median:3.07 stddev:0.01 (0.2%) stddev-sans-first:0.01
03:07:17 INFO - PID 20369 | Values: 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1 3.1
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:4.53 Median:4.53 stddev:0.35 (7.8%) stddev-sans-first:0.35
03:07:17 INFO - PID 20369 | Values: 4.2 4.2 4.3 4.6 5.4 4.5 4.5 4.5 4.2 5.0 4.6 4.4
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:4.48 Median:4.51 stddev:0.25 (5.6%) stddev-sans-first:0.24
03:07:17 INFO - PID 20369 | Values: 4.1 4.1 4.4 4.8 5.0 4.6 4.5 4.4 4.3 4.7 4.6 4.3
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:4.38 Median:4.42 stddev:0.16 (3.6%) stddev-sans-first:0.16
03:07:17 INFO - PID 20369 | Values: 4.6 4.2 4.2 4.3 4.4 4.6 4.3 4.2 4.6 4.5 4.6 4.2
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:4.33 Median:4.38 stddev:0.16 (3.8%) stddev-sans-first:0.15
03:07:17 INFO - PID 20369 | Values: 4.6 4.2 4.2 4.2 4.4 4.5 4.2 4.2 4.5 4.4 4.5 4.1
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:4.74 Median:4.71 stddev:0.06 (1.3%) stddev-sans-first:0.06
03:07:17 INFO - PID 20369 | Values: 4.7 4.7 4.7 4.8 4.7 4.8 4.7 4.7 4.7 4.8 4.7 4.8
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:4.70 Median:4.69 stddev:0.06 (1.3%) stddev-sans-first:0.06
03:07:17 INFO - PID 20369 | Values: 4.7 4.7 4.6 4.7 4.7 4.8 4.7 4.7 4.6 4.8 4.7 4.8
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:7.01 Median:7.01 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:07:17 INFO - PID 20369 | Values: 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:6.78 Median:6.78 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:07:17 INFO - PID 20369 | Values: 6.8 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8 6.8
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:6.99 Median:7.00 stddev:0.04 (0.6%) stddev-sans-first:0.04
03:07:17 INFO - PID 20369 | Values: 7.0 7.0 7.0 7.0 7.0 6.9 7.0 7.1 7.0 7.0 6.9 7.0
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:6.77 Median:6.77 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:07:17 INFO - PID 20369 | Values: 6.8 6.8 6.8 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.7 6.8
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:7.01 Median:7.01 stddev:0.03 (0.5%) stddev-sans-first:0.03
03:07:17 INFO - PID 20369 | Values: 7.0 6.9 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0 7.0
03:07:17 INFO - PID 20369 |
03:07:17 INFO - PID 20369 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:6.77 Median:6.78 stddev:0.03 (0.4%) stddev-sans-first:0.03
03:07:17 INFO - PID 20369 | Values: 6.8 6.8 6.8 6.7 6.8 6.8 6.8 6.8 6.8 6.8 6.7 6.8
03:07:17 INFO - PID 20369 | -------- Summary: end --------
03:07:17 INFO - PID 20369 |
03:07:18 INFO - TEST-INFO | 20369: exit 0
03:07:18 INFO - TEST-OK | basic_compositor_video | took 560970ms
03:07:18 INFO - TEST-START | glvideo
03:07:18 INFO - Initialising browser for glvideo test...
03:07:18 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:07:18 INFO - Installing Add-ons
03:07:18 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53596/getInfo.html -profile /tmp/tmpXTnWRI/profile
03:07:18 INFO - TEST-INFO | started process 21651 (/builds/slave/test/build/application/firefox/firefox http://localhost:53596/getInfo.html)
03:07:24 INFO - TEST-INFO | 21651: exit 0
03:07:24 INFO - Browser initialized.
03:07:24 INFO - Running cycle 1/1 for glvideo test...
03:07:24 INFO - Using env: {'DISPLAY': ':0',
03:07:24 INFO - 'HOME': '/home/cltbld',
03:07:24 INFO - 'JSGC_DISABLE_POISONING': '1',
03:07:24 INFO - 'LANG': 'en_US.UTF-8',
03:07:24 INFO - 'LANGUAGE': 'en_US:en',
03:07:24 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:07:24 INFO - 'LOGNAME': 'cltbld',
03:07:24 INFO - 'MAIL': '/var/mail/cltbld',
03:07:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:07:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:07:24 INFO - 'MOZ_ACCELERATED': '1',
03:07:24 INFO - 'MOZ_CRASHREPORTER': '1',
03:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:07:24 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:07:24 INFO - 'MOZ_NO_REMOTE': '1',
03:07:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:07:24 INFO - 'MOZ_WEBRENDER': '1',
03:07:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:07:24 INFO - 'NO_EM_RESTART': '1',
03:07:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:07:24 INFO - 'PWD': '/builds/slave/test',
03:07:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:07:24 INFO - 'RUST_BACKTRACE': 'full',
03:07:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:07:24 INFO - 'SHELL': '/bin/bash',
03:07:24 INFO - 'SHLVL': '1',
03:07:24 INFO - 'TERM': 'linux',
03:07:24 INFO - 'TMOUT': '86400',
03:07:24 INFO - 'USER': 'cltbld',
03:07:24 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
03:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:07:24 INFO - '_': '/tools/buildbot/bin/python'}
03:07:24 INFO - TEST-INFO | started process 21933 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXTnWRI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:07:25 INFO - PID 21933 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:07:25 INFO - PID 21933 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:07:26 INFO - PID 21933 |
03:07:26 INFO - PID 21933 | (/builds/slave/test/build/application/firefox/firefox:21994): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:07:26 INFO - PID 21933 |
03:07:27 INFO - PID 21933 | [talos glvideo result] Mean tick time across 100 ticks: 7.68875 ms
03:07:27 INFO - PID 21933 | Cycle 1(1): loaded http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html)
03:07:28 INFO - PID 21933 | [talos glvideo result] Mean tick time across 100 ticks: 6.506950000000001 ms
03:07:28 INFO - PID 21933 | Cycle 1(2): loaded http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html)
03:07:30 INFO - PID 21933 | [talos glvideo result] Mean tick time across 100 ticks: 6.55045 ms
03:07:30 INFO - PID 21933 | Cycle 1(3): loaded http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html)
03:07:31 INFO - PID 21933 | [talos glvideo result] Mean tick time across 100 ticks: 6.748399999999999 ms
03:07:31 INFO - PID 21933 | Cycle 1(4): loaded http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html)
03:07:32 INFO - PID 21933 | [talos glvideo result] Mean tick time across 100 ticks: 6.65195 ms
03:07:32 INFO - PID 21933 | Cycle 1(5): loaded http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:53596/tests/webgl/benchmarks/video/video_upload.html)
03:07:33 INFO - PID 21933 | __start_tp_report
03:07:33 INFO - PID 21933 | _x_x_mozilla_page_load
03:07:33 INFO - PID 21933 | _x_x_mozilla_page_load_details
03:07:33 INFO - PID 21933 | |i|pagename|runs|
03:07:33 INFO - PID 21933 | |0;Mean tick time across 100 ticks: ;7.68875;6.506950000000001;6.55045;6.748399999999999;6.65195
03:07:33 INFO - PID 21933 | __end_tp_report
03:07:33 INFO - PID 21933 | __start_cc_report
03:07:33 INFO - PID 21933 | _x_x_mozilla_cycle_collect,289
03:07:33 INFO - PID 21933 | __end_cc_report
03:07:33 INFO - PID 21933 | __startTimestamp1511089653218__endTimestamp
03:07:33 INFO - PID 21933 |
03:07:33 INFO - PID 21933 | ------- Summary: start -------
03:07:33 INFO - PID 21933 | Number of tests: 1
03:07:33 INFO - PID 21933 |
03:07:33 INFO - PID 21933 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.83 Median:6.65 stddev:0.49 (7.4%) stddev-sans-first:0.11
03:07:33 INFO - PID 21933 | Values: 7.7 6.5 6.6 6.7 6.7
03:07:33 INFO - PID 21933 | -------- Summary: end --------
03:07:33 INFO - PID 21933 |
03:07:33 INFO - PID 21933 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:07:34 INFO - TEST-INFO | 21933: exit 0
03:07:34 INFO - TEST-OK | glvideo | took 15409ms
03:07:34 INFO - TEST-START | displaylist_mutate
03:07:34 INFO - Initialising browser for displaylist_mutate test...
03:07:34 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
03:07:34 INFO - Installing Add-ons
03:07:34 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:53596/getInfo.html -profile /tmp/tmpiKJvJx/profile
03:07:34 INFO - TEST-INFO | started process 22171 (/builds/slave/test/build/application/firefox/firefox http://localhost:53596/getInfo.html)
03:07:40 INFO - TEST-INFO | 22171: exit 0
03:07:40 INFO - Browser initialized.
03:07:40 INFO - Running cycle 1/1 for displaylist_mutate test...
03:07:40 INFO - Using env: {'DISPLAY': ':0',
03:07:40 INFO - 'HOME': '/home/cltbld',
03:07:40 INFO - 'JSGC_DISABLE_POISONING': '1',
03:07:40 INFO - 'LANG': 'en_US.UTF-8',
03:07:40 INFO - 'LANGUAGE': 'en_US:en',
03:07:40 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:07:40 INFO - 'LOGNAME': 'cltbld',
03:07:40 INFO - 'MAIL': '/var/mail/cltbld',
03:07:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:07:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:07:40 INFO - 'MOZ_ACCELERATED': '1',
03:07:40 INFO - 'MOZ_CRASHREPORTER': '1',
03:07:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:07:40 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:07:40 INFO - 'MOZ_NO_REMOTE': '1',
03:07:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:07:40 INFO - 'MOZ_WEBRENDER': '1',
03:07:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:07:40 INFO - 'NO_EM_RESTART': '1',
03:07:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:07:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:07:40 INFO - 'PWD': '/builds/slave/test',
03:07:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:07:40 INFO - 'RUST_BACKTRACE': 'full',
03:07:40 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:07:40 INFO - 'SHELL': '/bin/bash',
03:07:40 INFO - 'SHLVL': '1',
03:07:40 INFO - 'TERM': 'linux',
03:07:40 INFO - 'TMOUT': '86400',
03:07:40 INFO - 'USER': 'cltbld',
03:07:40 INFO - 'XDG_SESSION_COOKIE': '3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903',
03:07:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:07:40 INFO - '_': '/tools/buildbot/bin/python'}
03:07:40 INFO - TEST-INFO | started process 22449 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiKJvJx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:07:41 INFO - PID 22449 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:07:41 INFO - PID 22449 | WebRender - OpenGL version new 3.2.0 NVIDIA 361.42
03:07:41 INFO - PID 22449 |
03:07:41 INFO - PID 22449 | (/builds/slave/test/build/application/firefox/firefox:22512): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:07:41 INFO - PID 22449 |
03:07:48 INFO - PID 22449 | Cycle 1(1): loaded http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html)
03:07:55 INFO - PID 22449 | Cycle 1(2): loaded http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html)
03:08:02 INFO - PID 22449 | Cycle 1(3): loaded http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html)
03:08:10 INFO - PID 22449 | Cycle 1(4): loaded http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html)
03:08:17 INFO - PID 22449 | Cycle 1(5): loaded http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:53596/tests/layout/benchmarks/displaylist_mutate.html)
03:08:17 INFO - PID 22449 | __start_tp_report
03:08:17 INFO - PID 22449 | _x_x_mozilla_page_load
03:08:17 INFO - PID 22449 | _x_x_mozilla_page_load_details
03:08:17 INFO - PID 22449 | |i|pagename|runs|
03:08:17 INFO - PID 22449 | |0;/displaylist_mutate.html;6256.58;6327.525000000001;6365.1050000000005;6427.36;6428.400000000001
03:08:17 INFO - PID 22449 | __end_tp_report
03:08:17 INFO - PID 22449 | __start_cc_report
03:08:17 INFO - PID 22449 | _x_x_mozilla_cycle_collect,254
03:08:17 INFO - PID 22449 | __end_cc_report
03:08:17 INFO - PID 22449 | __startTimestamp1511089697563__endTimestamp
03:08:17 INFO - PID 22449 |
03:08:17 INFO - PID 22449 | ------- Summary: start -------
03:08:17 INFO - PID 22449 | Number of tests: 1
03:08:17 INFO - PID 22449 |
03:08:17 INFO - PID 22449 | [#0] /displaylist_mutate.html Cycles:5 Average:6360.99 Median:6365.11 stddev:72.44 (1.1%) stddev-sans-first:49.53
03:08:17 INFO - PID 22449 | Values: 6256.6 6327.5 6365.1 6427.4 6428.4
03:08:17 INFO - PID 22449 | -------- Summary: end --------
03:08:17 INFO - PID 22449 |
03:08:17 INFO - PID 22449 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:08:18 INFO - TEST-INFO | 22449: exit 0
03:08:18 INFO - TEST-OK | displaylist_mutate | took 44264ms
03:08:18 INFO - SUITE-END | took 620s
03:08:18 INFO - Completed test suite (00:10:21)
03:08:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 4.413948742279336, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.508887959866221, 2.4902489626556017, 2.496738768718802, 2.484151490066225, 2.4841846026490066, 2.4755940594059407, 2.4735119538334707, 2.482340777502068, 2.4799586776859504, 2.4836382450331125, 2.476196369636964, 2.4803388429752067], "value": 2.482340777502068, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.454116564417178, 2.4603997539975397, 2.4486780905752754, 2.4640640394088678, 2.460959409594096, 2.4601968019680194, 2.463996305418719, 2.464304187192118, 2.4541472392638033, 2.4639162561576353, 2.4637253694581283, 2.460239852398524], "value": 2.460959409594096, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.515092204526404, 2.5210084033613445, 2.510949790794979, 2.511092050209205, 2.511280334728033, 2.5212983193277307, 2.5088294314381274, 2.521121848739496, 2.5105146443514648, 2.502714762301918, 2.5235996635828433, 2.513375209380235], "value": 2.511280334728033, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [2.5009124999999996, 2.4977278401997496, 2.5037922403003763, 2.503811013767209, 2.5064473684210533, 2.503610763454318, 2.5006000000000004, 2.49989375, 2.500581249999999, 2.504292866082604, 2.497602996254683, 2.5063533834586464], "value": 2.503610763454318, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.086718106995886, 3.0778256410256404, 3.0803850102669403, 3.0771025641025633, 3.0746362704918027, 3.077933333333332, 3.0709058341862843, 3.0772307692307703, 3.0708597748208817, 3.073862704918033, 3.08707304526749, 3.0840596094552937], "value": 3.0772307692307703, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.06861963190184, 3.0724884792626734, 3.0633001531393584, 3.0584480122324162, 3.072273425499231, 3.072127496159757, 3.0636140888208283, 3.067799079754601, 3.059013761467889, 3.063866768759571, 3.069064417177915, 3.058960244648317], "value": 3.063866768759571, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.191033519553076, 4.17867688022284, 4.262556818181818, 4.6023696319018415, 5.368121645796066, 4.539326777609683, 4.51181203007519, 4.457815750371469, 4.243946251768036, 5.016864548494982, 4.573201219512196, 4.367168850072784], "value": 4.51181203007519, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.132954545454543, 4.141335403726707, 4.425044247787608, 4.75119952494062, 4.962828784119107, 4.567420091324198, 4.454331848552337, 4.37840262582057, 4.25523404255319, 4.684449648711949, 4.629513888888891, 4.330400432900426], "value": 4.454331848552337, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.552572078907436, 4.225739436619715, 4.22565492957746, 4.250332861189802, 4.361366279069766, 4.5741387195121925, 4.305487804878048, 4.231840620592384, 4.580954198473284, 4.471430700447097, 4.574458841463416, 4.18458856345886], "value": 4.305487804878048, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.599195402298854, 4.176252609603346, 4.175688935281842, 4.229418604651162, 4.368165938864626, 4.494977528089895, 4.2108000000000025, 4.1851778242677815, 4.495617977528088, 4.397780219780215, 4.52593891402715, 4.142712215320912], "value": 4.229418604651162, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.695618153364633, 4.725220472440944, 4.7027978056426365, 4.793929712460064, 4.688179687500002, 4.808469551282046, 4.688937499999997, 4.694757433489829, 4.695500782472612, 4.8238344051446935, 4.6739563862928355, 4.831521739130435], "value": 4.7027978056426365, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.68371194379391, 4.684519906323187, 4.630416666666667, 4.6857025761124165, 4.673306074766353, 4.808461538461538, 4.652441860465115, 4.696690140845073, 4.641090487238982, 4.796091127098324, 4.684344262295074, 4.785502392344498], "value": 4.684519906323187, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.026323185011713, 7.043779342723, 7.042347417840369, 7.0118457943925225, 7.011693925233647, 6.9776976744186126, 7.010665887850473, 7.0100116822429905, 6.978883720930237, 6.99483682983683, 7.0267096018735336, 6.961287703016239], "value": 7.010665887850473, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.782372881355942, 6.781508474576265, 6.713439597315447, 6.759966216216206, 6.781067796610173, 6.7591216216216115, 6.805051020408155, 6.827969283276454, 6.759020270270264, 6.803673469387751, 6.8045578231292545, 6.782627118644068], "value": 6.781508474576265, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.9937878787878764, 6.9933682983683125, 7.000384615384617, 6.9771860465116164, 6.965765661252906, 6.928729792147805, 7.010654205607471, 7.077146226415092, 7.027529274004678, 7.011308411214945, 6.947002314814806, 6.978267441860463], "value": 6.9933682983683125, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.7798305084745865, 6.78057627118643, 6.781762711864391, 6.8042857142857365, 6.714781879194622, 6.758564189189201, 6.828754266211619, 6.7591216216216115, 6.759966216216206, 6.759594594594607, 6.736111111111111, 6.756993243243242], "value": 6.759594594594607, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.044577464788728, 6.9321362586605035, 6.994603729603724, 6.979000000000003, 7.044577464788728, 7.0268149882904085, 7.009894859813069, 6.961531322505796, 7.010023364485983, 7.04300469483568, 7.009521028037393, 7.010514018691588], "value": 7.009894859813069, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.78276271186441, 6.7795593220339025, 6.803265306122436, 6.715989932885924, 6.75885135135136, 6.804591836734679, 6.75993243243244, 6.757533783783795, 6.781288135593207, 6.762043918918927, 6.735084175084174, 6.783508474576278], "value": 6.762043918918927, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.68875, 6.506950000000001, 6.55045, 6.748399999999999, 6.65195], "value": 6.6012, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6256.58, 6327.525000000001, 6365.1050000000005, 6427.36, 6428.400000000001], "value": 6396.2325, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
03:08:18 INFO - Return code: 0
03:08:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:08:18 INFO - # TBPL SUCCESS #
03:08:18 INFO - Running post-action listener: _package_coverage_data
03:08:18 INFO - Running post-action listener: _resource_record_post_action
03:08:18 INFO - [mozharness: 2017-11-19 11:08:18.848885Z] Finished run-tests step (success)
03:08:18 INFO - Running post-run listener: _resource_record_post_run
03:08:18 INFO - Total resource usage - Wall time: 640s; CPU: 45.0%; Read bytes: 5672960; Write bytes: 345591808; Read time: 368; Write time: 256580
03:08:18 INFO - TinderboxPrint: CPU usage
45.5%
03:08:18 INFO - TinderboxPrint: I/O read bytes / time
5,672,960 / 368
03:08:18 INFO - TinderboxPrint: I/O write bytes / time
345,591,808 / 256,580
03:08:18 INFO - TinderboxPrint: CPU idle
2,598.4 (54.1%)
03:08:18 INFO - TinderboxPrint: CPU system
655.7 (13.6%)
03:08:18 INFO - TinderboxPrint: CPU user
1,529.0 (31.8%)
03:08:18 INFO - TinderboxPrint: Swap in / out
0 / 0
03:08:18 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 4956160; Read time: 0; Write time: 348
03:08:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:08:18 INFO - run-tests - Wall time: 622s; CPU: 46.0%; Read bytes: 5672960; Write bytes: 338751488; Read time: 368; Write time: 255956
03:08:19 INFO - Running post-run listener: _upload_blobber_files
03:08:19 INFO - Blob upload gear active.
03:08:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:08:19 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
03:08:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
03:08:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
03:08:19 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
03:08:19 INFO - SNIMissingWarning
03:08:19 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
03:08:19 INFO - InsecurePlatformWarning
03:08:19 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.)
03:08:19 INFO - SubjectAltNameWarning
03:08:19 INFO - (blobuploader) - INFO - Open directory for files ...
03:08:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:08:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:19 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:19 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
03:08:19 INFO - InsecurePlatformWarning
03:08:19 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.)
03:08:19 INFO - SubjectAltNameWarning
03:08:20 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
03:08:20 INFO - InsecurePlatformWarning
03:08:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:08:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:20 INFO - (blobuploader) - INFO - Done attempting.
03:08:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
03:08:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:20 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:20 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
03:08:20 INFO - InsecurePlatformWarning
03:08:20 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.)
03:08:20 INFO - SubjectAltNameWarning
03:08:21 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
03:08:21 INFO - InsecurePlatformWarning
03:08:21 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
03:08:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:21 INFO - (blobuploader) - INFO - Done attempting.
03:08:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
03:08:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:21 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:21 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
03:08:21 INFO - InsecurePlatformWarning
03:08:21 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.)
03:08:21 INFO - SubjectAltNameWarning
03:08:21 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
03:08:21 INFO - InsecurePlatformWarning
03:08:22 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
03:08:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:22 INFO - (blobuploader) - INFO - Done attempting.
03:08:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:08:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:08:22 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:08:22 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
03:08:22 INFO - InsecurePlatformWarning
03:08:22 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.)
03:08:22 INFO - SubjectAltNameWarning
03:08:24 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
03:08:24 INFO - InsecurePlatformWarning
03:08:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:08:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:08:24 INFO - (blobuploader) - INFO - Done attempting.
03:08:24 INFO - (blobuploader) - INFO - Iteration through files over.
03:08:24 INFO - Return code: 0
03:08:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:08:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:08:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb660617865226aee1cb02c965c58a5e2a648217d27cc592ab083df743e40e2fb7a147ee924c08b7524ac29a3335013ad9fcbc7614814cfe4ad9cfb5898ca0f3", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96545c9399a6ff51817f09d4ee778cedcf671753adf75616cd85eacee1bc6a298d8a0f550568660458880a3dcd0b8ea52154a577cd98c1b10d68c6b1efb7417a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1c70a9e0fb8689b5abe556846631210220ac2662fabee18ac7dc115bc074da9b41b909b88120d1ec8158b89069400dbfe4bf73ffd063d1db2fb3947db6a40ac", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06690d05a3a40450ea51c897ba8c0fd66817b0a0e4e2c5c2b2480290646b53746ff461d23c562f163986f98cf5a52fdffaabdcbc1f1d47ae1ccc51a5963ac63b"}
03:08:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:08:24 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:08:24 INFO - Contents:
03:08:24 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb660617865226aee1cb02c965c58a5e2a648217d27cc592ab083df743e40e2fb7a147ee924c08b7524ac29a3335013ad9fcbc7614814cfe4ad9cfb5898ca0f3", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96545c9399a6ff51817f09d4ee778cedcf671753adf75616cd85eacee1bc6a298d8a0f550568660458880a3dcd0b8ea52154a577cd98c1b10d68c6b1efb7417a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1c70a9e0fb8689b5abe556846631210220ac2662fabee18ac7dc115bc074da9b41b909b88120d1ec8158b89069400dbfe4bf73ffd063d1db2fb3947db6a40ac", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06690d05a3a40450ea51c897ba8c0fd66817b0a0e4e2c5c2b2480290646b53746ff461d23c562f163986f98cf5a52fdffaabdcbc1f1d47ae1ccc51a5963ac63b"}
03:08:24 INFO - Running post-run listener: copy_logs_to_upload_dir
03:08:24 INFO - Copying logs to upload dir...
03:08:24 INFO - mkdir: /builds/slave/test/build/upload/logs
03:08:24 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=702.127004
========= master_lag: 0.05 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 42 secs) (at 2017-11-19 03:08:24.748543) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-19 03:08:24.755688) =========
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=3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb660617865226aee1cb02c965c58a5e2a648217d27cc592ab083df743e40e2fb7a147ee924c08b7524ac29a3335013ad9fcbc7614814cfe4ad9cfb5898ca0f3", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96545c9399a6ff51817f09d4ee778cedcf671753adf75616cd85eacee1bc6a298d8a0f550568660458880a3dcd0b8ea52154a577cd98c1b10d68c6b1efb7417a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1c70a9e0fb8689b5abe556846631210220ac2662fabee18ac7dc115bc074da9b41b909b88120d1ec8158b89069400dbfe4bf73ffd063d1db2fb3947db6a40ac", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06690d05a3a40450ea51c897ba8c0fd66817b0a0e4e2c5c2b2480290646b53746ff461d23c562f163986f98cf5a52fdffaabdcbc1f1d47ae1ccc51a5963ac63b"}
build_url:https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011420
build_url: 'https://queue.taskcluster.net/v1/task/MXl7ZxliSaeQmdtSsZoFEg/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/eb660617865226aee1cb02c965c58a5e2a648217d27cc592ab083df743e40e2fb7a147ee924c08b7524ac29a3335013ad9fcbc7614814cfe4ad9cfb5898ca0f3", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96545c9399a6ff51817f09d4ee778cedcf671753adf75616cd85eacee1bc6a298d8a0f550568660458880a3dcd0b8ea52154a577cd98c1b10d68c6b1efb7417a", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d1c70a9e0fb8689b5abe556846631210220ac2662fabee18ac7dc115bc074da9b41b909b88120d1ec8158b89069400dbfe4bf73ffd063d1db2fb3947db6a40ac", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06690d05a3a40450ea51c897ba8c0fd66817b0a0e4e2c5c2b2480290646b53746ff461d23c562f163986f98cf5a52fdffaabdcbc1f1d47ae1ccc51a5963ac63b"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-19 03:08:24.788713) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-19 03:08:24.789058) =========
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=3297cc2eebb5444a7688c33f000001b5-1511088889.703984-824835903
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004575
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-19 03:08:24.869229) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-19 03:08:24.869539) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-19 03:08:24.869848) =========
========= Total master_lag: 0.24 =========