builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-062
starttime: 1513622046.84
results: success (0)
revision: 94e939c04e269ad8f8c454a1b3b5635c2ccff8c5
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:06.843625) =========
master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:06.844093) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:06.844369) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:06.938758) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:06.939489) =========
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=a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-12-18 10:34:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 93.6M=0s
2017-12-18 10:34:07 (93.6 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.146835
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:07.114555) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:07.114846) =========
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=a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.018477
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:07.164369) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-18 10:34:07.164702) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 94e939c04e269ad8f8c454a1b3b5635c2ccff8c5 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 94e939c04e269ad8f8c454a1b3b5635c2ccff8c5 --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=a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-12-18 10:34:07,211 truncating revision to first 12 chars
2017-12-18 10:34:07,211 Setting DEBUG logging.
2017-12-18 10:34:07,211 attempt 1/10
2017-12-18 10:34:07,211 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/94e939c04e26?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-12-18 10:34:07,618 unpacking tar archive at: mozilla-beta-94e939c04e26/testing/mozharness/
program finished with exit code 0
elapsedTime=1.056641
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-12-18 10:34:08.239416) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:08.239731) =========
script_repo_revision: 94e939c04e269ad8f8c454a1b3b5635c2ccff8c5
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:08.240128) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:08.240390) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-12-18 10:34:08.254624) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 47 secs) (at 2017-12-18 10:34:08.255032) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
10:34:08 INFO - MultiFileLogger online at 20171218 10:34:08 in /builds/slave/test
10:34:08 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
10:34:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
10:34:08 INFO - {'append_to_log': False,
10:34:08 INFO - 'base_work_dir': '/builds/slave/test',
10:34:08 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
10:34:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
10:34:08 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
10:34:08 INFO - 'buildbot_json_path': 'buildprops.json',
10:34:08 INFO - 'code_coverage': False,
10:34:08 INFO - 'config_files': ('talos/linux_config.py',),
10:34:08 INFO - 'default_actions': ('clobber',
10:34:08 INFO - 'read-buildbot-config',
10:34:08 INFO - 'download-and-extract',
10:34:08 INFO - 'populate-webroot',
10:34:08 INFO - 'create-virtualenv',
10:34:08 INFO - 'install',
10:34:08 INFO - 'setup-mitmproxy',
10:34:08 INFO - 'run-tests'),
10:34:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
10:34:08 INFO - 'disable_ccov_upload': False,
10:34:08 INFO - 'disable_stylo': False,
10:34:08 INFO - 'download_minidump_stackwalk': True,
10:34:08 INFO - 'download_symbols': 'ondemand',
10:34:08 INFO - 'e10s': False,
10:34:08 INFO - 'enable_stylo': False,
10:34:08 INFO - 'enable_webrender': False,
10:34:08 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
10:34:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
10:34:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
10:34:08 INFO - 'gecko_profile': False,
10:34:08 INFO - 'gecko_profile_interval': 0,
10:34:08 INFO - 'installer_path': 'installer.exe',
10:34:08 INFO - 'jsd_code_coverage': False,
10:34:08 INFO - 'log_level': 'info',
10:34:08 INFO - 'log_name': 'talos',
10:34:08 INFO - 'log_to_console': True,
10:34:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
10:34:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
10:34:08 INFO - 'opt_config_files': (),
10:34:08 INFO - 'pip_index': False,
10:34:08 INFO - 'suite': 'g4-e10s',
10:34:08 INFO - 'system_bits': '32',
10:34:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
10:34:08 INFO - 'title': 'talos-linux64-ix-062',
10:34:08 INFO - 'tooltool_cache': '/builds/tooltool_cache',
10:34:08 INFO - 'use_talos_json': True,
10:34:08 INFO - 'verify': 'False',
10:34:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
10:34:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
10:34:08 INFO - 'work_dir': 'build'}
10:34:08 INFO - [mozharness: 2017-12-18 18:34:08.449633Z] Running clobber step.
10:34:08 INFO - Running pre-action listener: _resource_record_pre_action
10:34:08 INFO - Running main action method: clobber
10:34:08 INFO - rmtree: /builds/slave/test/build
10:34:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
10:34:10 INFO - Running post-action listener: _resource_record_post_action
10:34:10 INFO - [mozharness: 2017-12-18 18:34:10.059333Z] Finished clobber step (success)
10:34:10 INFO - [mozharness: 2017-12-18 18:34:10.059430Z] Running read-buildbot-config step.
10:34:10 INFO - Running pre-action listener: _resource_record_pre_action
10:34:10 INFO - Running main action method: read_buildbot_config
10:34:10 INFO - Using buildbot properties:
10:34:10 INFO - {
10:34:10 INFO - "project": "",
10:34:10 INFO - "product": "firefox",
10:34:10 INFO - "who": "csabou@mozilla.com",
10:34:10 INFO - "installer_path": "public/build/target.tar.bz2",
10:34:10 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
10:34:10 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
10:34:10 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
10:34:10 INFO - "stage_platform": "linux64",
10:34:10 INFO - "basedir": "/builds/slave/test",
10:34:10 INFO - "buildnumber": 88,
10:34:10 INFO - "platform": "ubuntu64_hw",
10:34:10 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/",
10:34:10 INFO - "slavebuilddir": "test",
10:34:10 INFO - "taskId": "aOfm3RjvSmGYWtlK-OTn8A",
10:34:10 INFO - "branch": "mozilla-beta",
10:34:10 INFO - "script_repo_revision": "production",
10:34:10 INFO - "revision": "94e939c04e269ad8f8c454a1b3b5635c2ccff8c5",
10:34:10 INFO - "slavename": "talos-linux64-ix-062",
10:34:10 INFO - "repo_path": "releases/mozilla-beta"
10:34:10 INFO - }
10:34:10 INFO - Finding installer, test and symbols from parent task.
10:34:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aOfm3RjvSmGYWtlK-OTn8A'}, attempt #1
10:34:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aOfm3RjvSmGYWtlK-OTn8A'}, attempt #1
10:34:11 INFO - Task dependencies: IkbY7NNsSOWAbs2IKlWjiw
10:34:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw'}, attempt #1
10:34:11 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2
10:34:11 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.test_packages.json
10:34:11 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.crashreporter-symbols.zip
10:34:11 INFO - Running post-action listener: _resource_record_post_action
10:34:11 INFO - [mozharness: 2017-12-18 18:34:11.453647Z] Finished read-buildbot-config step (success)
10:34:11 INFO - [mozharness: 2017-12-18 18:34:11.453862Z] Running download-and-extract step.
10:34:11 INFO - Running pre-action listener: _resource_record_pre_action
10:34:11 INFO - Running main action method: download_and_extract
10:34:11 INFO - mkdir: /builds/slave/test/build/tests
10:34: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')]}
10:34:11 INFO - trying https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.test_packages.json
10:34:11 INFO - Downloading https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
10:34:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
10:34:12 INFO - Downloaded 989 bytes.
10:34:12 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
10:34:12 INFO - Using the following test package requirements:
10:34:12 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
10:34:12 INFO - u'common': [u'target.common.tests.zip'],
10:34:12 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
10:34:12 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
10:34:12 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
10:34:12 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
10:34:12 INFO - u'mozbase': [u'target.common.tests.zip'],
10:34:12 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
10:34:12 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
10:34:12 INFO - u'web-platform': [u'target.common.tests.zip',
10:34:12 INFO - u'target.web-platform.tests.tar.gz'],
10:34:12 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
10:34:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
10:34:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.common.tests.zip
10:34:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.common.tests.zip'}, attempt #1
10:34:12 INFO - Fetch https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.common.tests.zip into memory
10:34:13 INFO - Content-Length response header: 58328734
10:34:13 INFO - Bytes received: 58328734
10:34:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.talos.tests.zip
10:34:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
10:34:24 INFO - Fetch https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.talos.tests.zip into memory
10:34:25 INFO - Content-Length response header: 18772095
10:34:25 INFO - Bytes received: 18772095
10:34:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:26 INFO - trying https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2
10:34:26 INFO - Downloading https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
10:34:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
10:34:28 INFO - Downloaded 54121798 bytes.
10:34:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2
10:34:28 INFO - mkdir: /builds/slave/test/properties
10:34:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
10:34:28 INFO - Writing to file /builds/slave/test/properties/build_url
10:34:28 INFO - Contents:
10:34:28 INFO - build_url:https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2
10:34:28 INFO - Running post-action listener: _resource_record_post_action
10:34:28 INFO - Running post-action listener: find_tests_for_verification
10:34:28 INFO - Running post-action listener: set_extra_try_arguments
10:34:28 INFO - [mozharness: 2017-12-18 18:34:28.706114Z] Finished download-and-extract step (success)
10:34:28 INFO - [mozharness: 2017-12-18 18:34:28.706208Z] Running populate-webroot step.
10:34:28 INFO - Running pre-action listener: _resource_record_pre_action
10:34:28 INFO - Running main action method: populate_webroot
10:34:28 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
10:34:28 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['tresize',
10:34:28 INFO - 'tcanvasmark']},
10:34:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
10:34:28 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['dromaeo_css',
10:34:28 INFO - 'kraken']},
10:34:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
10:34:28 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
10:34:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'tests': ['damp', 'tps']},
10:34:28 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['damp', 'tps']},
10:34:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
10:34:28 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['dromaeo_dom']},
10:34:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
10:34:28 INFO - 'glvideo',
10:34:28 INFO - 'displaylist_mutate']},
10:34:28 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['basic_compositor_video',
10:34:28 INFO - 'glvideo']},
10:34:28 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
10:34:28 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['ts_paint_webext',
10:34:28 INFO - 'tp5o_webext']},
10:34:28 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
10:34:28 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:28 INFO - 'talos_options': ['--mitmproxy',
10:34:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:28 INFO - '--firstNonBlankPaint'],
10:34:28 INFO - 'tests': ['tp6_google_heavy',
10:34:28 INFO - 'tp6_youtube_heavy',
10:34:28 INFO - 'tp6_amazon_heavy',
10:34:28 INFO - 'tp6_facebook_heavy']},
10:34:28 INFO - 'other-e10s': {'tests': ['a11yr',
10:34:28 INFO - 'ts_paint',
10:34:28 INFO - 'tpaint',
10:34:28 INFO - 'sessionrestore',
10:34:28 INFO - 'sessionrestore_many_windows',
10:34:28 INFO - 'sessionrestore_no_auto_restore',
10:34:28 INFO - 'tabpaint',
10:34:28 INFO - 'cpstartup']},
10:34:28 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['a11yr',
10:34:28 INFO - 'ts_paint',
10:34:28 INFO - 'tpaint',
10:34:28 INFO - 'sessionrestore',
10:34:28 INFO - 'sessionrestore_many_windows',
10:34:28 INFO - 'sessionrestore_no_auto_restore',
10:34:28 INFO - 'tabpaint',
10:34:28 INFO - 'cpstartup']},
10:34:28 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
10:34:28 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
10:34:28 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['perf_reftest_singletons']},
10:34:28 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['perf_reftest']},
10:34:28 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
10:34:28 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['speedometer']},
10:34:28 INFO - 'svgr-e10s': {'tests': ['tsvgx',
10:34:28 INFO - 'tsvgr_opacity',
10:34:28 INFO - 'tart',
10:34:28 INFO - 'tscrollx',
10:34:28 INFO - 'tsvg_static']},
10:34:28 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['tsvgx',
10:34:28 INFO - 'tsvgr_opacity',
10:34:28 INFO - 'tart',
10:34:28 INFO - 'tscrollx',
10:34:28 INFO - 'tsvg_static']},
10:34:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
10:34:28 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'talos_options': ['--disable-stylo'],
10:34:28 INFO - 'tests': ['tp5o']},
10:34:28 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:28 INFO - 'talos_options': ['--mitmproxy',
10:34:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:28 INFO - '--firstNonBlankPaint'],
10:34:28 INFO - 'tests': ['tp6_google',
10:34:28 INFO - 'tp6_youtube',
10:34:28 INFO - 'tp6_amazon',
10:34:28 INFO - 'tp6_facebook']},
10:34:28 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:28 INFO - 'talos_options': ['--disable-stylo',
10:34:28 INFO - '--mitmproxy',
10:34:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:28 INFO - '--firstNonBlankPaint'],
10:34:28 INFO - 'tests': ['tp6_google',
10:34:28 INFO - 'tp6_youtube',
10:34:28 INFO - 'tp6_amazon',
10:34:28 INFO - 'tp6_facebook']},
10:34:28 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
10:34:28 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
10:34:28 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
10:34:28 INFO - 'talos_options': ['--stylo-threads=1',
10:34:28 INFO - '--mitmproxy',
10:34:28 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
10:34:28 INFO - '--firstNonBlankPaint'],
10:34:28 INFO - 'tests': ['tp6_google',
10:34:28 INFO - 'tp6_youtube',
10:34:28 INFO - 'tp6_amazon',
10:34:28 INFO - 'tp6_facebook']},
10:34:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'talos_options': ['--xperf_path',
10:34:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:34:28 INFO - 'tests': ['tp5n']},
10:34:28 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
10:34:28 INFO - 'talos_options': ['--disable-stylo',
10:34:28 INFO - '--xperf_path',
10:34:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
10:34:28 INFO - 'tests': ['tp5n']}}}
10:34:28 INFO - Running post-action listener: _resource_record_post_action
10:34:28 INFO - [mozharness: 2017-12-18 18:34:28.724489Z] Finished populate-webroot step (success)
10:34:28 INFO - [mozharness: 2017-12-18 18:34:28.724581Z] Running create-virtualenv step.
10:34:28 INFO - Running pre-action listener: _resource_record_pre_action
10:34:28 INFO - Running main action method: create_virtualenv
10:34:28 INFO - Creating virtualenv /builds/slave/test/build/venv
10:34:28 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
10:34:28 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
10:34:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
10:34:29 INFO - Using real prefix '/usr'
10:34:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python
10:34:29 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
10:34:30 INFO - Installing setuptools, pip, wheel...done.
10:34:30 INFO - Return code: 0
10:34:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
10:34: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')]}
10:34:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34: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')]}
10:34:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:30 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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
10:34:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
10:34:30 INFO - Using env: {'DISPLAY': ':0',
10:34:30 INFO - 'HOME': '/home/cltbld',
10:34:30 INFO - 'LANG': 'en_US.UTF-8',
10:34:30 INFO - 'LANGUAGE': 'en_US:en',
10:34:30 INFO - 'LOGNAME': 'cltbld',
10:34:30 INFO - 'MAIL': '/var/mail/cltbld',
10:34:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:30 INFO - 'MOZ_NO_REMOTE': '1',
10:34:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:30 INFO - 'NO_EM_RESTART': '1',
10:34:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:30 INFO - 'PWD': '/builds/slave/test',
10:34:30 INFO - 'SHELL': '/bin/bash',
10:34:30 INFO - 'SHLVL': '1',
10:34:30 INFO - 'TERM': 'linux',
10:34:30 INFO - 'TMOUT': '86400',
10:34:30 INFO - 'USER': 'cltbld',
10:34:30 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:30 INFO - '_': '/tools/buildbot/bin/python'}
10:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
10:34:31 INFO - Return code: 0
10:34:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
10:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:31 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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
10:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
10:34:31 INFO - Using env: {'DISPLAY': ':0',
10:34:31 INFO - 'HOME': '/home/cltbld',
10:34:31 INFO - 'LANG': 'en_US.UTF-8',
10:34:31 INFO - 'LANGUAGE': 'en_US:en',
10:34:31 INFO - 'LOGNAME': 'cltbld',
10:34:31 INFO - 'MAIL': '/var/mail/cltbld',
10:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:31 INFO - 'MOZ_NO_REMOTE': '1',
10:34:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:31 INFO - 'NO_EM_RESTART': '1',
10:34:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:31 INFO - 'PWD': '/builds/slave/test',
10:34:31 INFO - 'SHELL': '/bin/bash',
10:34:31 INFO - 'SHLVL': '1',
10:34:31 INFO - 'TERM': 'linux',
10:34:31 INFO - 'TMOUT': '86400',
10:34:31 INFO - 'USER': 'cltbld',
10:34:31 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:31 INFO - '_': '/tools/buildbot/bin/python'}
10:34:31 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:31 INFO - Collecting psutil>=3.1.1
10:34:32 INFO - Installing collected packages: psutil
10:34:32 INFO - Successfully installed psutil-3.1.1
10:34:32 INFO - Return code: 0
10:34:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
10:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
10:34:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
10:34:32 INFO - Using env: {'DISPLAY': ':0',
10:34:32 INFO - 'HOME': '/home/cltbld',
10:34:32 INFO - 'LANG': 'en_US.UTF-8',
10:34:32 INFO - 'LANGUAGE': 'en_US:en',
10:34:32 INFO - 'LOGNAME': 'cltbld',
10:34:32 INFO - 'MAIL': '/var/mail/cltbld',
10:34:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:32 INFO - 'MOZ_NO_REMOTE': '1',
10:34:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:32 INFO - 'NO_EM_RESTART': '1',
10:34:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:32 INFO - 'PWD': '/builds/slave/test',
10:34:32 INFO - 'SHELL': '/bin/bash',
10:34:32 INFO - 'SHLVL': '1',
10:34:32 INFO - 'TERM': 'linux',
10:34:32 INFO - 'TMOUT': '86400',
10:34:32 INFO - 'USER': 'cltbld',
10:34:32 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:32 INFO - '_': '/tools/buildbot/bin/python'}
10:34:33 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:33 INFO - Collecting mozsystemmonitor==0.3
10:34:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
10:34:34 INFO - Installing collected packages: mozsystemmonitor
10:34:34 INFO - Successfully installed mozsystemmonitor-0.3
10:34:34 INFO - Return code: 0
10:34:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
10:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
10:34:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
10:34:34 INFO - Using env: {'DISPLAY': ':0',
10:34:34 INFO - 'HOME': '/home/cltbld',
10:34:34 INFO - 'LANG': 'en_US.UTF-8',
10:34:34 INFO - 'LANGUAGE': 'en_US:en',
10:34:34 INFO - 'LOGNAME': 'cltbld',
10:34:34 INFO - 'MAIL': '/var/mail/cltbld',
10:34:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:34 INFO - 'MOZ_NO_REMOTE': '1',
10:34:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:34 INFO - 'NO_EM_RESTART': '1',
10:34:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:34 INFO - 'PWD': '/builds/slave/test',
10:34:34 INFO - 'SHELL': '/bin/bash',
10:34:34 INFO - 'SHLVL': '1',
10:34:34 INFO - 'TERM': 'linux',
10:34:34 INFO - 'TMOUT': '86400',
10:34:34 INFO - 'USER': 'cltbld',
10:34:34 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:34 INFO - '_': '/tools/buildbot/bin/python'}
10:34:34 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:34 INFO - Collecting jsonschema==2.5.1
10:34:35 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
10:34:35 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
10:34:37 INFO - Installing collected packages: functools32, jsonschema
10:34:37 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
10:34:37 INFO - Return code: 0
10:34:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
10:34: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')]}
10:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34: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')]}
10:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34: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', '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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34: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', 'functools32==3.2.3-2'] in /builds/slave/test/build
10:34: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 functools32==3.2.3-2
10:34:37 INFO - Using env: {'DISPLAY': ':0',
10:34:37 INFO - 'HOME': '/home/cltbld',
10:34:37 INFO - 'LANG': 'en_US.UTF-8',
10:34:37 INFO - 'LANGUAGE': 'en_US:en',
10:34:37 INFO - 'LOGNAME': 'cltbld',
10:34:37 INFO - 'MAIL': '/var/mail/cltbld',
10:34:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:37 INFO - 'MOZ_NO_REMOTE': '1',
10:34:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:37 INFO - 'NO_EM_RESTART': '1',
10:34:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:37 INFO - 'PWD': '/builds/slave/test',
10:34:37 INFO - 'SHELL': '/bin/bash',
10:34:37 INFO - 'SHLVL': '1',
10:34:37 INFO - 'TERM': 'linux',
10:34:37 INFO - 'TMOUT': '86400',
10:34:37 INFO - 'USER': 'cltbld',
10:34:37 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:37 INFO - '_': '/tools/buildbot/bin/python'}
10:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
10:34:37 INFO - Return code: 0
10:34:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
10:34: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')]}
10:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34: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')]}
10:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34: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', '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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34: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', 'blobuploader==1.2.4'] in /builds/slave/test/build
10:34: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 blobuploader==1.2.4
10:34:37 INFO - Using env: {'DISPLAY': ':0',
10:34:37 INFO - 'HOME': '/home/cltbld',
10:34:37 INFO - 'LANG': 'en_US.UTF-8',
10:34:37 INFO - 'LANGUAGE': 'en_US:en',
10:34:37 INFO - 'LOGNAME': 'cltbld',
10:34:37 INFO - 'MAIL': '/var/mail/cltbld',
10:34:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:37 INFO - 'MOZ_NO_REMOTE': '1',
10:34:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:37 INFO - 'NO_EM_RESTART': '1',
10:34:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:37 INFO - 'PWD': '/builds/slave/test',
10:34:37 INFO - 'SHELL': '/bin/bash',
10:34:37 INFO - 'SHLVL': '1',
10:34:37 INFO - 'TERM': 'linux',
10:34:37 INFO - 'TMOUT': '86400',
10:34:37 INFO - 'USER': 'cltbld',
10:34:37 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:37 INFO - '_': '/tools/buildbot/bin/python'}
10:34:38 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:38 INFO - Collecting blobuploader==1.2.4
10:34:39 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
10:34:40 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
10:34:41 INFO - Installing collected packages: requests, docopt, blobuploader
10:34:41 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
10:34:41 INFO - Return code: 0
10:34:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv
10:34:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:41 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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
10:34:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
10:34:41 INFO - Using env: {'DISPLAY': ':0',
10:34:41 INFO - 'HOME': '/home/cltbld',
10:34:41 INFO - 'LANG': 'en_US.UTF-8',
10:34:41 INFO - 'LANGUAGE': 'en_US:en',
10:34:41 INFO - 'LOGNAME': 'cltbld',
10:34:41 INFO - 'MAIL': '/var/mail/cltbld',
10:34:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:41 INFO - 'MOZ_NO_REMOTE': '1',
10:34:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:41 INFO - 'NO_EM_RESTART': '1',
10:34:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:41 INFO - 'PWD': '/builds/slave/test',
10:34:41 INFO - 'SHELL': '/bin/bash',
10:34:41 INFO - 'SHLVL': '1',
10:34:41 INFO - 'TERM': 'linux',
10:34:41 INFO - 'TMOUT': '86400',
10:34:41 INFO - 'USER': 'cltbld',
10:34:41 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:41 INFO - '_': '/tools/buildbot/bin/python'}
10:34:41 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:41 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
10:34:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
10:34:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
10:34:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
10:34:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
10:34:42 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
10:34:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
10:34:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
10:34:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
10:34:43 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
10:34:43 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
10:34:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
10:34:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
10:34:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
10:34:44 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
10:34:44 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
10:34:45 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
10:34:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
10:34:45 INFO - Running setup.py install for manifestparser: started
10:34:45 INFO - Running setup.py install for manifestparser: finished with status 'done'
10:34:45 INFO - Running setup.py install for mozcrash: started
10:34:45 INFO - Running setup.py install for mozcrash: finished with status 'done'
10:34:45 INFO - Running setup.py install for mozdebug: started
10:34:45 INFO - Running setup.py install for mozdebug: finished with status 'done'
10:34:45 INFO - Running setup.py install for mozdevice: started
10:34:46 INFO - Running setup.py install for mozdevice: finished with status 'done'
10:34:46 INFO - Running setup.py install for mozfile: started
10:34:46 INFO - Running setup.py install for mozfile: finished with status 'done'
10:34:46 INFO - Running setup.py install for mozhttpd: started
10:34:46 INFO - Running setup.py install for mozhttpd: finished with status 'done'
10:34:46 INFO - Running setup.py install for mozinfo: started
10:34:46 INFO - Running setup.py install for mozinfo: finished with status 'done'
10:34:46 INFO - Running setup.py install for mozInstall: started
10:34:46 INFO - Running setup.py install for mozInstall: finished with status 'done'
10:34:46 INFO - Running setup.py install for mozleak: started
10:34:47 INFO - Running setup.py install for mozleak: finished with status 'done'
10:34:47 INFO - Running setup.py install for mozlog: started
10:34:47 INFO - Running setup.py install for mozlog: finished with status 'done'
10:34:47 INFO - Running setup.py install for moznetwork: started
10:34:47 INFO - Running setup.py install for moznetwork: finished with status 'done'
10:34:47 INFO - Running setup.py install for mozprocess: started
10:34:47 INFO - Running setup.py install for mozprocess: finished with status 'done'
10:34:47 INFO - Running setup.py install for mozprofile: started
10:34:48 INFO - Running setup.py install for mozprofile: finished with status 'done'
10:34:48 INFO - Running setup.py install for mozrunner: started
10:34:48 INFO - Running setup.py install for mozrunner: finished with status 'done'
10:34:48 INFO - Running setup.py install for mozscreenshot: started
10:34:48 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
10:34:48 INFO - Running setup.py install for moztest: started
10:34:48 INFO - Running setup.py install for moztest: finished with status 'done'
10:34:48 INFO - Running setup.py install for mozversion: started
10:34:49 INFO - Running setup.py install for mozversion: finished with status 'done'
10:34:49 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
10:34:49 INFO - Return code: 0
10:34:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv
10:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:49 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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
10:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
10:34:49 INFO - Using env: {'DISPLAY': ':0',
10:34:49 INFO - 'HOME': '/home/cltbld',
10:34:49 INFO - 'LANG': 'en_US.UTF-8',
10:34:49 INFO - 'LANGUAGE': 'en_US:en',
10:34:49 INFO - 'LOGNAME': 'cltbld',
10:34:49 INFO - 'MAIL': '/var/mail/cltbld',
10:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:49 INFO - 'MOZ_NO_REMOTE': '1',
10:34:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:49 INFO - 'NO_EM_RESTART': '1',
10:34:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:49 INFO - 'PWD': '/builds/slave/test',
10:34:49 INFO - 'SHELL': '/bin/bash',
10:34:49 INFO - 'SHLVL': '1',
10:34:49 INFO - 'TERM': 'linux',
10:34:49 INFO - 'TMOUT': '86400',
10:34:49 INFO - 'USER': 'cltbld',
10:34:49 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:49 INFO - '_': '/tools/buildbot/bin/python'}
10:34:49 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:49 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
10:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
10:34:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
10:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
10:34:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
10:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
10:34:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
10:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
10:34:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
10:34:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
10:34:50 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
10:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
10:34:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
10:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
10:34:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
10:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
10:34:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
10:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
10:34:51 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
10:34:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
10:34:51 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
10:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
10:34:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
10:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
10:34:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
10:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
10:34:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
10:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
10:34:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
10:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
10:34:52 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
10:34:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
10:34:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
10:34:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
10:34:53 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
10:34:54 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
10:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
10:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
10:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
10:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
10:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
10:34:54 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
10:34:54 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
10:34:55 INFO - Installing collected packages: six, blessings
10:34:55 INFO - Successfully installed blessings-1.6 six-1.10.0
10:34:55 INFO - Return code: 0
10:34:55 INFO - Done creating virtualenv /builds/slave/test/build/venv.
10:34:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
10:34:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
10:34:56 INFO - Reading from file tmpfile_stdout
10:34:56 INFO - Reading from file tmpfile_stderr
10:34:56 INFO - Current package versions:
10:34:56 INFO - blessings == 1.6
10:34:56 INFO - blobuploader == 1.2.4
10:34:56 INFO - docopt == 0.6.1
10:34:56 INFO - functools32 == 3.2.3.post2
10:34:56 INFO - jsonschema == 2.5.1
10:34:56 INFO - manifestparser == 1.2
10:34:56 INFO - mozInstall == 1.14
10:34:56 INFO - mozcrash == 1.0
10:34:56 INFO - mozdebug == 0.1
10:34:56 INFO - mozdevice == 0.51
10:34:56 INFO - mozfile == 1.2
10:34:56 INFO - mozhttpd == 0.7
10:34:56 INFO - mozinfo == 0.10
10:34:56 INFO - mozleak == 0.1
10:34:56 INFO - mozlog == 3.5
10:34:56 INFO - moznetwork == 0.27
10:34:56 INFO - mozprocess == 0.25
10:34:56 INFO - mozprofile == 0.29
10:34:56 INFO - mozrunner == 6.13
10:34:56 INFO - mozscreenshot == 0.1
10:34:56 INFO - mozsystemmonitor == 0.3
10:34:56 INFO - moztest == 0.8
10:34:56 INFO - mozversion == 1.4
10:34:56 INFO - psutil == 3.1.1
10:34:56 INFO - requests == 1.2.3
10:34:56 INFO - six == 1.10.0
10:34:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv
10:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
10:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
10:34:56 INFO - Using env: {'DISPLAY': ':0',
10:34:56 INFO - 'HOME': '/home/cltbld',
10:34:56 INFO - 'LANG': 'en_US.UTF-8',
10:34:56 INFO - 'LANGUAGE': 'en_US:en',
10:34:56 INFO - 'LOGNAME': 'cltbld',
10:34:56 INFO - 'MAIL': '/var/mail/cltbld',
10:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:34:56 INFO - 'MOZ_NO_REMOTE': '1',
10:34:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:34:56 INFO - 'NO_EM_RESTART': '1',
10:34:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:34:56 INFO - 'PWD': '/builds/slave/test',
10:34:56 INFO - 'SHELL': '/bin/bash',
10:34:56 INFO - 'SHLVL': '1',
10:34:56 INFO - 'TERM': 'linux',
10:34:56 INFO - 'TMOUT': '86400',
10:34:56 INFO - 'USER': 'cltbld',
10:34:56 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:34:56 INFO - '_': '/tools/buildbot/bin/python'}
10:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
10:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
10:34:56 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
10:34:57 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:34:58 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
10:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
10:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
10:34:58 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
10:34:58 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:34:59 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
10:34:59 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:00 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
10:35:00 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:01 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
10:35:01 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
10:35:03 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
10:35:03 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
10:35:03 INFO - Found existing installation: requests 1.2.3
10:35:03 INFO - Uninstalling requests-1.2.3:
10:35:03 INFO - Successfully uninstalled requests-1.2.3
10:35:03 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
10:35:03 INFO - Return code: 0
10:35:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
10:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
10:35:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:35:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
10:35:03 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 0x124c2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1424a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15c7930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, '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': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
10:35:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema'] in /builds/slave/test/build
10:35:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema
10:35:03 INFO - Using env: {'DISPLAY': ':0',
10:35:03 INFO - 'HOME': '/home/cltbld',
10:35:03 INFO - 'LANG': 'en_US.UTF-8',
10:35:03 INFO - 'LANGUAGE': 'en_US:en',
10:35:03 INFO - 'LOGNAME': 'cltbld',
10:35:03 INFO - 'MAIL': '/var/mail/cltbld',
10:35:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:03 INFO - 'MOZ_NO_REMOTE': '1',
10:35:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:03 INFO - 'NO_EM_RESTART': '1',
10:35:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:03 INFO - 'PWD': '/builds/slave/test',
10:35:03 INFO - 'SHELL': '/bin/bash',
10:35:03 INFO - 'SHLVL': '1',
10:35:03 INFO - 'TERM': 'linux',
10:35:03 INFO - 'TMOUT': '86400',
10:35:03 INFO - 'USER': 'cltbld',
10:35:03 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:35:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:03 INFO - '_': '/tools/buildbot/bin/python'}
10:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple
10:35:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
10:35:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
10:35:04 INFO - Return code: 0
10:35:04 INFO - Running post-action listener: _resource_record_post_action
10:35:04 INFO - Running post-action listener: _start_resource_monitoring
10:35:04 INFO - Starting resource monitoring.
10:35:04 INFO - [mozharness: 2017-12-18 18:35:04.070581Z] Finished create-virtualenv step (success)
10:35:04 INFO - [mozharness: 2017-12-18 18:35:04.071168Z] Running install step.
10:35:04 INFO - Running pre-action listener: _resource_record_pre_action
10:35:04 INFO - Running main action method: install
10:35:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
10:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
10:35:04 INFO - Reading from file tmpfile_stdout
10:35:04 INFO - Reading from file tmpfile_stderr
10:35:04 INFO - Detecting whether we're running mozinstall >=1.0...
10:35:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
10:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
10:35:04 INFO - Reading from file tmpfile_stdout
10:35:04 INFO - Output received:
10:35:04 INFO - Usage: mozinstall [options] installer
10:35:04 INFO - Options:
10:35:04 INFO - -h, --help show this help message and exit
10:35:04 INFO - -d DEST, --destination=DEST
10:35:04 INFO - Directory to install application into. [default:
10:35:04 INFO - "/builds/slave/test"]
10:35:04 INFO - --app=APP Application being installed. [default: firefox]
10:35:04 INFO - mkdir: /builds/slave/test/build/application
10:35:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
10:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
10:35:19 INFO - Reading from file tmpfile_stdout
10:35:19 INFO - Output received:
10:35:19 INFO - /builds/slave/test/build/application/firefox/firefox
10:35:19 INFO - Running post-action listener: _resource_record_post_action
10:35:19 INFO - [mozharness: 2017-12-18 18:35:19.782473Z] Finished install step (success)
10:35:19 INFO - [mozharness: 2017-12-18 18:35:19.782713Z] Running setup-mitmproxy step.
10:35:19 INFO - Running pre-action listener: _resource_record_pre_action
10:35:19 INFO - Running main action method: setup_mitmproxy
10:35:19 INFO - Skipping: mitmproxy is not required
10:35:19 INFO - Running post-action listener: _resource_record_post_action
10:35:19 INFO - [mozharness: 2017-12-18 18:35:19.783820Z] Finished setup-mitmproxy step (success)
10:35:19 INFO - [mozharness: 2017-12-18 18:35:19.784034Z] Running run-tests step.
10:35:19 INFO - Running pre-action listener: _resource_record_pre_action
10:35:19 INFO - Running pre-action listener: _set_gcov_prefix
10:35:19 INFO - Running main action method: run_tests
10:35:19 WARNING - Try message not found.
10:35:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
10:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
10:35:19 INFO - Python 2.7.3
10:35:19 INFO - Return code: 0
10:35:19 INFO - grabbing minidump binary from tooltool
10:35:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
10:35:19 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 0x15c4ad0>, '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 0x15c59d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15c5e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
10:35:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
10:35:19 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
10:35:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
10:35:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
10:35:19 INFO - Return code: 0
10:35:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
10:35:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
10:35:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
10:35:19 INFO - ENV: RUST_BACKTRACE is now full
10:35:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
10:35:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
10:35:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
10:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-062 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
10:35:19 INFO - Using env: {'DISPLAY': ':0',
10:35:19 INFO - 'HOME': '/home/cltbld',
10:35:19 INFO - 'LANG': 'en_US.UTF-8',
10:35:19 INFO - 'LANGUAGE': 'en_US:en',
10:35:19 INFO - 'LOGNAME': 'cltbld',
10:35:19 INFO - 'MAIL': '/var/mail/cltbld',
10:35:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:35:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:35:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:19 INFO - 'MOZ_NO_REMOTE': '1',
10:35:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:35:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:19 INFO - 'NO_EM_RESTART': '1',
10:35:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:19 INFO - 'PWD': '/builds/slave/test',
10:35:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:35:19 INFO - 'RUST_BACKTRACE': 'full',
10:35:19 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:35:19 INFO - 'SHELL': '/bin/bash',
10:35:19 INFO - 'SHLVL': '1',
10:35:19 INFO - 'TERM': 'linux',
10:35:19 INFO - 'TMOUT': '86400',
10:35:19 INFO - 'USER': 'cltbld',
10:35:19 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:35:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:19 INFO - '_': '/tools/buildbot/bin/python'}
10:35:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-062', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
10:35:20 INFO - mozversion application_buildid: 20171218171353
10:35:20 INFO - mozversion application_changeset: 94e939c04e269ad8f8c454a1b3b5635c2ccff8c5
10:35:20 INFO - mozversion application_display_name: Firefox
10:35:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
10:35:20 INFO - mozversion application_name: Firefox
10:35:20 INFO - mozversion application_remotingname: firefox
10:35:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
10:35:20 INFO - mozversion application_vendor: Mozilla
10:35:20 INFO - mozversion application_version: 58.0
10:35:20 INFO - mozversion platform_buildid: 20171218171353
10:35:20 INFO - mozversion platform_changeset: 94e939c04e269ad8f8c454a1b3b5635c2ccff8c5
10:35:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
10:35:20 INFO - mozversion platform_version: 58.0
10:35:20 INFO - using testdate: 1513622120
10:35:20 INFO - actual date: 1513622120
10:35:20 INFO - starting webserver on 'localhost:48553'
10:35:20 INFO - SUITE-START | Running 3 tests
10:35:20 INFO - TEST-START | basic_compositor_video
10:35:20 INFO - Initialising browser for basic_compositor_video test...
10:35:20 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48553/getInfo.html -profile /tmp/tmp1Sp5cg/profile
10:35:20 INFO - TEST-INFO | started process 17179 (/builds/slave/test/build/application/firefox/firefox http://localhost:48553/getInfo.html)
10:35:25 INFO - TEST-INFO | 17179: exit 0
10:35:25 INFO - Browser initialized.
10:35:25 INFO - Running cycle 1/1 for basic_compositor_video test...
10:35:25 INFO - Using env: {'DISPLAY': ':0',
10:35:25 INFO - 'HOME': '/home/cltbld',
10:35:25 INFO - 'JSGC_DISABLE_POISONING': '1',
10:35:25 INFO - 'LANG': 'en_US.UTF-8',
10:35:25 INFO - 'LANGUAGE': 'en_US:en',
10:35:25 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
10:35:25 INFO - 'LOGNAME': 'cltbld',
10:35:25 INFO - 'MAIL': '/var/mail/cltbld',
10:35:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:35:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:35:25 INFO - 'MOZ_CRASHREPORTER': '1',
10:35:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:35:25 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
10:35:25 INFO - 'MOZ_NO_REMOTE': '1',
10:35:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:35:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:35:25 INFO - 'NO_EM_RESTART': '1',
10:35:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:35:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:35:25 INFO - 'PWD': '/builds/slave/test',
10:35:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:35:25 INFO - 'RUST_BACKTRACE': 'full',
10:35:25 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:35:25 INFO - 'SHELL': '/bin/bash',
10:35:25 INFO - 'SHLVL': '1',
10:35:25 INFO - 'TERM': 'linux',
10:35:25 INFO - 'TMOUT': '86400',
10:35:25 INFO - 'USER': 'cltbld',
10:35:25 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:35:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:35:25 INFO - '_': '/tools/buildbot/bin/python'}
10:35:25 INFO - TEST-INFO | started process 17418 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1Sp5cg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
10:35:26 INFO - PID 17418 |
10:35:26 INFO - PID 17418 | (/builds/slave/test/build/application/firefox/firefox:17468): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
10:35:26 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6903943661971832 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.66987061769616 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7660682754561514 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7521409807355515 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.038216689098251 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.922196078431372 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.5638123515439424 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4662131715771247 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.376944444444443 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.420596205962061 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.20753401360544 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 10.364430051813468 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.156152416356882 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639175531914898 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450062240663886 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.198109756097548 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.495383141762474 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.989532967032934 ms/frame
10:36:12 INFO - PID 17418 |
10:36:12 INFO - PID 17418 | Cycle 1(1): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:36:59 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6867060146149522 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668419516263553 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.718336197021764 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7212134251290871 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.139434482758621 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.134349173553718 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.464284064665129 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3513400335008376 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.759078694817664 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.408135135135131 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.275034246575347 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.855443349753688 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.321377358490583 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.695454545454522 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.049257028112448 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.048403614457852 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674143968871585 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.428542857142872 ms/frame
10:36:59 INFO - PID 17418 |
10:36:59 INFO - PID 17418 | Cycle 1(2): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:37:45 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6826668536174987 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689866555462884 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7804629080118695 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7459511343804544 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1842050209205 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1408281573498975 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.417010250569476 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.339941569282137 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.815891472868217 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.634098591549295 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.48966783216784 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 10.102171717171714 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239606741573029 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.812675675675681 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659957805907156 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.19682926829272 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.542211538461562 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.930573770491794 ms/frame
10:37:45 INFO - PID 17418 |
10:37:45 INFO - PID 17418 | Cycle 1(3): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:38:31 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6828295008412788 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669708333333335 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7543830409356724 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7332409012131715 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.055499999999999 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9459368836291917 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.4249486301369876 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3791216216216196 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.628714821763602 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.652259887005654 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 9.969784053156141 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.902772277227726 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.073708487084865 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.367383419689114 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.712351694915277 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.197865853658518 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.676945525291822 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.363664772727258 ms/frame
10:38:31 INFO - PID 17418 |
10:38:31 INFO - PID 17418 | Cycle 1(4): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:39:17 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6906647887323942 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710401002506268 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.766283107710418 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7592304309586622 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115349794238683 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.159241164241163 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.6465127582016987 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6307622504537256 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.618061797752806 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5274447513812195 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.001983333333325 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.951492537313433 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.070848708487073 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.52750000000003 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.931228448275855 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05403614457832 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.908194444444437 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.30076271186441 ms/frame
10:39:17 INFO - PID 17418 |
10:39:17 INFO - PID 17418 | Cycle 1(5): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:40:03 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6807366946778712 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669845575959933 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7678432527990575 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7533041191936893 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 3.896402597402597 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.175521920668056 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.580322195704059 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5276984126984092 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.715457142857146 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683650568181819 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.346017241379302 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.52621428571429 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.281672932330824 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.697379679144404 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.712033898305089 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.423385093167708 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.539057692307717 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.874864130434775 ms/frame
10:40:03 INFO - PID 17418 |
10:40:03 INFO - PID 17418 | Cycle 1(6): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:40:49 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681684417040359 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673416666666667 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.767931644077784 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.759700967458224 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1966573426573435 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.203056722689077 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.4573214285714284 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3857106598984803 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.486352833638025 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.619733146067416 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.206037414965989 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 10.313402061855667 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.11296296296295 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.810837837837862 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.822307692307685 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.04993975903615 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.955219123505955 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.432171428571456 ms/frame
10:40:49 INFO - PID 17418 |
10:40:49 INFO - PID 17418 | Cycle 1(7): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:41:36 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6848877035373389 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6689115929941618 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7367621527777777 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6909382924767549 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.138048275862068 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.141469979296066 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.421790193842645 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3625546218487417 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.760076775431862 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.557958333333327 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.792895683453235 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.85325123152709 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114425925925927 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.934098360655751 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.880000000000004 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.274417177914085 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.76709803921569 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.051408839779008 ms/frame
10:41:36 INFO - PID 17418 |
10:41:36 INFO - PID 17418 | Cycle 1(8): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:42:22 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6829444756029164 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683194328607174 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.765229411764706 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7272409326424873 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.098790983606558 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9066015625000006 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.4325800915331794 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3974108658743645 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.566929499072358 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.571796657381619 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.06776845637585 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.528238095238079 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.238258426966285 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.813918918918942 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.100545851528391 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.197957317073193 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452156488549633 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050662983425404 ms/frame
10:42:22 INFO - PID 17418 |
10:42:22 INFO - PID 17418 | Cycle 1(9): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:43:08 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6840179573512908 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6725000000000003 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7504434072345387 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.720128976784179 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1729485396383845 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.185899581589959 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.4376345933562433 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.346295986622072 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.566456400742115 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.557708333333327 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.101902356902356 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.663623188405792 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.111759259259257 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754166666666675 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.044391304347823 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.980568862275433 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.497375478927212 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.178072625698324 ms/frame
10:43:08 INFO - PID 17418 |
10:43:08 INFO - PID 17418 | Cycle 1(10): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:43:54 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6765335195530728 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6669916666666666 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7463911525029099 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7230060292850997 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.184728033472805 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.985358565737051 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.448999999999999 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3858037225042334 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5569444444444445 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.588449720670393 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.002316666666665 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 9.80487745098039 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.239419475655442 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754301075268794 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.876309012875538 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05274096385545 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.631434108527145 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992609890109884 ms/frame
10:43:54 INFO - PID 17418 |
10:43:54 INFO - PID 17418 | Cycle 1(11): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:44:40 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681002801120448 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6733444816053513 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7334402079722704 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.71302654109589 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_startup = 4.08823569482289 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.899873294346979 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_startup = 3.4130375426621153 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3557802013422853 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682821969696968 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5408587257617725 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_startup = 10.170355932203393 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.480p.60fps.webm_scale_2_inclip = 10.475287958115176 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.27868421052632 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.587248677248667 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 13.044652173913029 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.199939024390249 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.629127906976754 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.23853932584269 ms/frame
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | Cycle 1(12): loaded http://localhost:48553/tests/video/video_playback.html (next: http://localhost:48553/tests/video/video_playback.html)
10:44:40 INFO - PID 17418 | __start_tp_report
10:44:40 INFO - PID 17418 | _x_x_mozilla_page_load
10:44:40 INFO - PID 17418 | _x_x_mozilla_page_load_details
10:44:40 INFO - PID 17418 | |i|pagename|runs|
10:44:40 INFO - PID 17418 | |0;240p.120fps.mp4_scale_1_startup;1.6903943661971832;1.6867060146149522;1.6826668536174987;1.6828295008412788;1.6906647887323942;1.6807366946778712;1.681684417040359;1.6848877035373389;1.6829444756029164;1.6840179573512908;1.6765335195530728;1.681002801120448
10:44:40 INFO - PID 17418 | |1;240p.120fps.mp4_scale_1_inclip;1.66987061769616;1.668419516263553;1.6689866555462884;1.6669708333333335;1.6710401002506268;1.669845575959933;1.6673416666666667;1.6689115929941618;1.6683194328607174;1.6725000000000003;1.6669916666666666;1.6733444816053513
10:44:40 INFO - PID 17418 | |2;240p.120fps.mp4_scale_1.1_startup;1.7660682754561514;1.718336197021764;1.7804629080118695;1.7543830409356724;1.766283107710418;1.7678432527990575;1.767931644077784;1.7367621527777777;1.765229411764706;1.7504434072345387;1.7463911525029099;1.7334402079722704
10:44:40 INFO - PID 17418 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7521409807355515;1.7212134251290871;1.7459511343804544;1.7332409012131715;1.7592304309586622;1.7533041191936893;1.759700967458224;1.6909382924767549;1.7272409326424873;1.720128976784179;1.7230060292850997;1.71302654109589
10:44:40 INFO - PID 17418 | |4;240p.120fps.mp4_scale_2_startup;4.038216689098251;4.139434482758621;4.1842050209205;4.055499999999999;4.115349794238683;3.896402597402597;4.1966573426573435;4.138048275862068;4.098790983606558;4.1729485396383845;4.184728033472805;4.08823569482289
10:44:40 INFO - PID 17418 | |5;240p.120fps.mp4_scale_2_inclip;3.922196078431372;4.134349173553718;4.1408281573498975;3.9459368836291917;4.159241164241163;4.175521920668056;4.203056722689077;4.141469979296066;3.9066015625000006;4.185899581589959;3.985358565737051;3.899873294346979
10:44:40 INFO - PID 17418 | |6;480p.60fps.webm_scale_1_startup;3.5638123515439424;3.464284064665129;3.417010250569476;3.4249486301369876;3.6465127582016987;3.580322195704059;3.4573214285714284;3.421790193842645;3.4325800915331794;3.4376345933562433;3.448999999999999;3.4130375426621153
10:44:40 INFO - PID 17418 | |7;480p.60fps.webm_scale_1_inclip;3.4662131715771247;3.3513400335008376;3.339941569282137;3.3791216216216196;3.6307622504537256;3.5276984126984092;3.3857106598984803;3.3625546218487417;3.3974108658743645;3.346295986622072;3.3858037225042334;3.3557802013422853
10:44:40 INFO - PID 17418 | |8;480p.60fps.webm_scale_1.1_startup;5.376944444444443;5.759078694817664;5.815891472868217;5.628714821763602;5.618061797752806;5.715457142857146;5.486352833638025;5.760076775431862;5.566929499072358;5.566456400742115;5.5569444444444445;5.682821969696968
10:44:40 INFO - PID 17418 | |9;480p.60fps.webm_scale_1.1_inclip;5.420596205962061;5.408135135135131;5.634098591549295;5.652259887005654;5.5274447513812195;5.683650568181819;5.619733146067416;5.557958333333327;5.571796657381619;5.557708333333327;5.588449720670393;5.5408587257617725
10:44:40 INFO - PID 17418 | |10;480p.60fps.webm_scale_2_startup;10.20753401360544;10.275034246575347;10.48966783216784;9.969784053156141;10.001983333333325;10.346017241379302;10.206037414965989;10.792895683453235;10.06776845637585;10.101902356902356;10.002316666666665;10.170355932203393
10:44:40 INFO - PID 17418 | |11;480p.60fps.webm_scale_2_inclip;10.364430051813468;9.855443349753688;10.102171717171714;9.902772277227726;9.951492537313433;9.52621428571429;10.313402061855667;9.85325123152709;9.528238095238079;9.663623188405792;9.80487745098039;10.475287958115176
10:44:40 INFO - PID 17418 | |12;1080p.60fps.mp4_scale_1_startup;11.156152416356882;11.321377358490583;11.239606741573029;11.073708487084865;11.070848708487073;11.281672932330824;11.11296296296295;11.114425925925927;11.238258426966285;11.111759259259257;11.239419475655442;11.27868421052632
10:44:40 INFO - PID 17418 | |13;1080p.60fps.mp4_scale_1_inclip;10.639175531914898;10.695454545454522;10.812675675675681;10.367383419689114;10.52750000000003;10.697379679144404;10.810837837837862;10.934098360655751;10.813918918918942;10.754166666666675;10.754301075268794;10.587248677248667
10:44:40 INFO - PID 17418 | |14;1080p.60fps.mp4_scale_1.1_startup;12.450062240663886;12.049257028112448;12.659957805907156;12.712351694915277;12.931228448275855;12.712033898305089;12.822307692307685;12.880000000000004;13.100545851528391;13.044391304347823;12.876309012875538;13.044652173913029
10:44:40 INFO - PID 17418 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.198109756097548;12.048403614457852;12.19682926829272;12.197865853658518;12.05403614457832;12.423385093167708;12.04993975903615;12.274417177914085;12.197957317073193;11.980568862275433;12.05274096385545;12.199939024390249
10:44:40 INFO - PID 17418 | |16;1080p.60fps.mp4_scale_2_startup;11.495383141762474;11.674143968871585;11.542211538461562;11.676945525291822;11.908194444444437;11.539057692307717;11.955219123505955;11.76709803921569;11.452156488549633;11.497375478927212;11.631434108527145;11.629127906976754
10:44:40 INFO - PID 17418 | |17;1080p.60fps.mp4_scale_2_inclip;10.989532967032934;11.428542857142872;10.930573770491794;11.363664772727258;11.30076271186441;10.874864130434775;11.432171428571456;11.051408839779008;11.050662983425404;11.178072625698324;10.992609890109884;11.23853932584269
10:44:40 INFO - PID 17418 | __end_tp_report
10:44:40 INFO - PID 17418 | __start_cc_report
10:44:40 INFO - PID 17418 | _x_x_mozilla_cycle_collect,553
10:44:40 INFO - PID 17418 | __end_cc_report
10:44:40 INFO - PID 17418 | __startTimestamp1513622680957__endTimestamp
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | ------- Summary: start -------
10:44:40 INFO - PID 17418 | Number of tests: 18
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
10:44:40 INFO - PID 17418 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
10:44:40 INFO - PID 17418 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.75 Median:1.77 stddev:0.02 (1.0%) stddev-sans-first:0.02
10:44:40 INFO - PID 17418 | Values: 1.8 1.7 1.8 1.8 1.8 1.8 1.8 1.7 1.8 1.8 1.7 1.7
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.73 Median:1.74 stddev:0.02 (1.2%) stddev-sans-first:0.02
10:44:40 INFO - PID 17418 | Values: 1.8 1.7 1.7 1.7 1.8 1.8 1.8 1.7 1.7 1.7 1.7 1.7
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.11 Median:4.14 stddev:0.08 (2.0%) stddev-sans-first:0.09
10:44:40 INFO - PID 17418 | Values: 4.0 4.1 4.2 4.1 4.1 3.9 4.2 4.1 4.1 4.2 4.2 4.1
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.07 Median:4.14 stddev:0.12 (3.0%) stddev-sans-first:0.12
10:44:40 INFO - PID 17418 | Values: 3.9 4.1 4.1 3.9 4.2 4.2 4.2 4.1 3.9 4.2 4.0 3.9
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.48 Median:3.45 stddev:0.08 (2.2%) stddev-sans-first:0.08
10:44:40 INFO - PID 17418 | Values: 3.6 3.5 3.4 3.4 3.6 3.6 3.5 3.4 3.4 3.4 3.4 3.4
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.41 Median:3.39 stddev:0.09 (2.6%) stddev-sans-first:0.09
10:44:40 INFO - PID 17418 | Values: 3.5 3.4 3.3 3.4 3.6 3.5 3.4 3.4 3.4 3.3 3.4 3.4
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.63 Median:5.66 stddev:0.13 (2.2%) stddev-sans-first:0.10
10:44:40 INFO - PID 17418 | Values: 5.4 5.8 5.8 5.6 5.6 5.7 5.5 5.8 5.6 5.6 5.6 5.7
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.56 Median:5.58 stddev:0.08 (1.5%) stddev-sans-first:0.07
10:44:40 INFO - PID 17418 | Values: 5.4 5.4 5.6 5.7 5.5 5.7 5.6 5.6 5.6 5.6 5.6 5.5
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.22 Median:10.24 stddev:0.24 (2.3%) stddev-sans-first:0.25
10:44:40 INFO - PID 17418 | Values: 10.2 10.3 10.5 10.0 10.0 10.3 10.2 10.8 10.1 10.1 10.0 10.2
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.95 Median:9.73 stddev:0.31 (3.2%) stddev-sans-first:0.30
10:44:40 INFO - PID 17418 | Values: 10.4 9.9 10.1 9.9 10.0 9.5 10.3 9.9 9.5 9.7 9.8 10.5
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.19 Median:11.24 stddev:0.09 (0.8%) stddev-sans-first:0.09
10:44:40 INFO - PID 17418 | Values: 11.2 11.3 11.2 11.1 11.1 11.3 11.1 11.1 11.2 11.1 11.2 11.3
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.70 Median:10.75 stddev:0.15 (1.4%) stddev-sans-first:0.16
10:44:40 INFO - PID 17418 | Values: 10.6 10.7 10.8 10.4 10.5 10.7 10.8 10.9 10.8 10.8 10.8 10.6
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.77 Median:12.88 stddev:0.29 (2.3%) stddev-sans-first:0.29
10:44:40 INFO - PID 17418 | Values: 12.5 12.0 12.7 12.7 12.9 12.7 12.8 12.9 13.1 13.0 12.9 13.0
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:12.16 Median:12.20 stddev:0.12 (1.0%) stddev-sans-first:0.13
10:44:40 INFO - PID 17418 | Values: 12.2 12.0 12.2 12.2 12.1 12.4 12.0 12.3 12.2 12.0 12.1 12.2
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.65 Median:11.65 stddev:0.16 (1.4%) stddev-sans-first:0.16
10:44:40 INFO - PID 17418 | Values: 11.5 11.7 11.5 11.7 11.9 11.5 12.0 11.8 11.5 11.5 11.6 11.6
10:44:40 INFO - PID 17418 |
10:44:40 INFO - PID 17418 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.15 Median:11.21 stddev:0.20 (1.8%) stddev-sans-first:0.20
10:44:40 INFO - PID 17418 | Values: 11.0 11.4 10.9 11.4 11.3 10.9 11.4 11.1 11.1 11.2 11.0 11.2
10:44:40 INFO - PID 17418 | -------- Summary: end --------
10:44:40 INFO - PID 17418 |
10:44:41 INFO - TEST-INFO | 17418: exit 0
10:44:41 INFO - TEST-OK | basic_compositor_video | took 561296ms
10:44:41 INFO - TEST-START | glvideo
10:44:41 INFO - Initialising browser for glvideo test...
10:44:41 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48553/getInfo.html -profile /tmp/tmpTkA8un/profile
10:44:41 INFO - TEST-INFO | started process 18632 (/builds/slave/test/build/application/firefox/firefox http://localhost:48553/getInfo.html)
10:44:46 INFO - TEST-INFO | 18632: exit 0
10:44:46 INFO - Browser initialized.
10:44:46 INFO - Running cycle 1/1 for glvideo test...
10:44:46 INFO - Using env: {'DISPLAY': ':0',
10:44:46 INFO - 'HOME': '/home/cltbld',
10:44:46 INFO - 'JSGC_DISABLE_POISONING': '1',
10:44:46 INFO - 'LANG': 'en_US.UTF-8',
10:44:46 INFO - 'LANGUAGE': 'en_US:en',
10:44:46 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
10:44:46 INFO - 'LOGNAME': 'cltbld',
10:44:46 INFO - 'MAIL': '/var/mail/cltbld',
10:44:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:44:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:44:46 INFO - 'MOZ_CRASHREPORTER': '1',
10:44:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:44:46 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
10:44:46 INFO - 'MOZ_NO_REMOTE': '1',
10:44:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:44:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:44:46 INFO - 'NO_EM_RESTART': '1',
10:44:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:44:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:44:46 INFO - 'PWD': '/builds/slave/test',
10:44:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:44:46 INFO - 'RUST_BACKTRACE': 'full',
10:44:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:44:46 INFO - 'SHELL': '/bin/bash',
10:44:46 INFO - 'SHLVL': '1',
10:44:46 INFO - 'TERM': 'linux',
10:44:46 INFO - 'TMOUT': '86400',
10:44:46 INFO - 'USER': 'cltbld',
10:44:46 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:44:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:44:46 INFO - '_': '/tools/buildbot/bin/python'}
10:44:46 INFO - TEST-INFO | started process 18866 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTkA8un/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
10:44:47 INFO - PID 18866 |
10:44:47 INFO - PID 18866 | (/builds/slave/test/build/application/firefox/firefox:18915): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
10:44:47 INFO - PID 18866 |
10:44:48 INFO - PID 18866 | [talos glvideo result] Mean tick time across 100 ticks: 7.079400000000001 ms
10:44:48 INFO - PID 18866 | Cycle 1(1): loaded http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html)
10:44:50 INFO - PID 18866 | [talos glvideo result] Mean tick time across 100 ticks: 6.501849999999999 ms
10:44:50 INFO - PID 18866 | Cycle 1(2): loaded http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html)
10:44:51 INFO - PID 18866 | [talos glvideo result] Mean tick time across 100 ticks: 6.6435 ms
10:44:51 INFO - PID 18866 | Cycle 1(3): loaded http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html)
10:44:52 INFO - PID 18866 | [talos glvideo result] Mean tick time across 100 ticks: 6.558900000000001 ms
10:44:52 INFO - PID 18866 | Cycle 1(4): loaded http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html)
10:44:54 INFO - PID 18866 | [talos glvideo result] Mean tick time across 100 ticks: 6.60145 ms
10:44:54 INFO - PID 18866 | Cycle 1(5): loaded http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:48553/tests/webgl/benchmarks/video/video_upload.html)
10:44:54 INFO - PID 18866 | __start_tp_report
10:44:54 INFO - PID 18866 | _x_x_mozilla_page_load
10:44:54 INFO - PID 18866 | _x_x_mozilla_page_load_details
10:44:54 INFO - PID 18866 | |i|pagename|runs|
10:44:54 INFO - PID 18866 | |0;Mean tick time across 100 ticks: ;7.079400000000001;6.501849999999999;6.6435;6.558900000000001;6.60145
10:44:54 INFO - PID 18866 | __end_tp_report
10:44:54 INFO - PID 18866 | __start_cc_report
10:44:54 INFO - PID 18866 | _x_x_mozilla_cycle_collect,241
10:44:54 INFO - PID 18866 | __end_cc_report
10:44:54 INFO - PID 18866 | __startTimestamp1513622694551__endTimestamp
10:44:54 INFO - PID 18866 |
10:44:54 INFO - PID 18866 | ------- Summary: start -------
10:44:54 INFO - PID 18866 | Number of tests: 1
10:44:54 INFO - PID 18866 |
10:44:54 INFO - PID 18866 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.68 Median:6.60 stddev:0.23 (3.5%) stddev-sans-first:0.06
10:44:54 INFO - PID 18866 | Values: 7.1 6.5 6.6 6.6 6.6
10:44:54 INFO - PID 18866 | -------- Summary: end --------
10:44:54 INFO - PID 18866 |
10:44:54 INFO - PID 18866 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
10:44:55 INFO - TEST-INFO | 18866: exit 0
10:44:55 INFO - TEST-OK | glvideo | took 13621ms
10:44:55 INFO - TEST-START | displaylist_mutate
10:44:55 INFO - Initialising browser for displaylist_mutate test...
10:44:55 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:48553/getInfo.html -profile /tmp/tmpDegyyI/profile
10:44:55 INFO - TEST-INFO | started process 19089 (/builds/slave/test/build/application/firefox/firefox http://localhost:48553/getInfo.html)
10:45:00 INFO - TEST-INFO | 19089: exit 0
10:45:00 INFO - Browser initialized.
10:45:00 INFO - Running cycle 1/1 for displaylist_mutate test...
10:45:00 INFO - Using env: {'DISPLAY': ':0',
10:45:00 INFO - 'HOME': '/home/cltbld',
10:45:00 INFO - 'JSGC_DISABLE_POISONING': '1',
10:45:00 INFO - 'LANG': 'en_US.UTF-8',
10:45:00 INFO - 'LANGUAGE': 'en_US:en',
10:45:00 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
10:45:00 INFO - 'LOGNAME': 'cltbld',
10:45:00 INFO - 'MAIL': '/var/mail/cltbld',
10:45:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
10:45:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
10:45:00 INFO - 'MOZ_CRASHREPORTER': '1',
10:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
10:45:00 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
10:45:00 INFO - 'MOZ_NO_REMOTE': '1',
10:45:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
10:45:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
10:45:00 INFO - 'NO_EM_RESTART': '1',
10:45:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
10:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
10:45:00 INFO - 'PWD': '/builds/slave/test',
10:45:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
10:45:00 INFO - 'RUST_BACKTRACE': 'full',
10:45:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
10:45:00 INFO - 'SHELL': '/bin/bash',
10:45:00 INFO - 'SHLVL': '1',
10:45:00 INFO - 'TERM': 'linux',
10:45:00 INFO - 'TMOUT': '86400',
10:45:00 INFO - 'USER': 'cltbld',
10:45:00 INFO - 'XDG_SESSION_COOKIE': 'a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942',
10:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
10:45:00 INFO - '_': '/tools/buildbot/bin/python'}
10:45:00 INFO - TEST-INFO | started process 19332 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDegyyI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
10:45:00 INFO - PID 19332 |
10:45:00 INFO - PID 19332 | (/builds/slave/test/build/application/firefox/firefox:19382): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
10:45:00 INFO - PID 19332 |
10:45:09 INFO - PID 19332 | Cycle 1(1): loaded http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html)
10:45:19 INFO - PID 19332 | Cycle 1(2): loaded http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html)
10:45:28 INFO - PID 19332 | Cycle 1(3): loaded http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html)
10:45:29 INFO - PID 19332 | Waiting for idle-callback
10:45:38 INFO - PID 19332 | Cycle 1(4): loaded http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html)
10:45:47 INFO - PID 19332 | Cycle 1(5): loaded http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:48553/tests/layout/benchmarks/displaylist_mutate.html)
10:45:47 INFO - PID 19332 | __start_tp_report
10:45:47 INFO - PID 19332 | _x_x_mozilla_page_load
10:45:47 INFO - PID 19332 | _x_x_mozilla_page_load_details
10:45:47 INFO - PID 19332 | |i|pagename|runs|
10:45:47 INFO - PID 19332 | |0;/displaylist_mutate.html;8296.59;8914.235;8568.415;8686.705;8502.570000000002
10:45:47 INFO - PID 19332 | __end_tp_report
10:45:47 INFO - PID 19332 | __start_cc_report
10:45:47 INFO - PID 19332 | _x_x_mozilla_cycle_collect,220
10:45:47 INFO - PID 19332 | __end_cc_report
10:45:47 INFO - PID 19332 | __startTimestamp1513622747694__endTimestamp
10:45:47 INFO - PID 19332 |
10:45:47 INFO - PID 19332 | ------- Summary: start -------
10:45:47 INFO - PID 19332 | Number of tests: 1
10:45:47 INFO - PID 19332 |
10:45:47 INFO - PID 19332 | [#0] /displaylist_mutate.html Cycles:5 Average:8593.70 Median:8568.42 stddev:228.37 (2.7%) stddev-sans-first:180.98
10:45:47 INFO - PID 19332 | Values: 8296.6 8914.2 8568.4 8686.7 8502.6
10:45:47 INFO - PID 19332 | -------- Summary: end --------
10:45:47 INFO - PID 19332 |
10:45:48 INFO - PID 19332 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
10:45:48 INFO - TEST-INFO | 19332: exit 0
10:45:48 INFO - TEST-OK | displaylist_mutate | took 53194ms
10:45:48 INFO - SUITE-END | took 628s
10:45:48 INFO - Completed test suite (00:10:28)
10:45:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.6167111683609185, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6903943661971832, 1.6867060146149522, 1.6826668536174987, 1.6828295008412788, 1.6906647887323942, 1.6807366946778712, 1.681684417040359, 1.6848877035373389, 1.6829444756029164, 1.6840179573512908, 1.6765335195530728, 1.681002801120448], "value": 1.6828295008412788, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.66987061769616, 1.668419516263553, 1.6689866555462884, 1.6669708333333335, 1.6710401002506268, 1.669845575959933, 1.6673416666666667, 1.6689115929941618, 1.6683194328607174, 1.6725000000000003, 1.6669916666666666, 1.6733444816053513], "value": 1.6689115929941618, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7660682754561514, 1.718336197021764, 1.7804629080118695, 1.7543830409356724, 1.766283107710418, 1.7678432527990575, 1.767931644077784, 1.7367621527777777, 1.765229411764706, 1.7504434072345387, 1.7463911525029099, 1.7334402079722704], "value": 1.7543830409356724, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7521409807355515, 1.7212134251290871, 1.7459511343804544, 1.7332409012131715, 1.7592304309586622, 1.7533041191936893, 1.759700967458224, 1.6909382924767549, 1.7272409326424873, 1.720128976784179, 1.7230060292850997, 1.71302654109589], "value": 1.7272409326424873, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.038216689098251, 4.139434482758621, 4.1842050209205, 4.055499999999999, 4.115349794238683, 3.896402597402597, 4.1966573426573435, 4.138048275862068, 4.098790983606558, 4.1729485396383845, 4.184728033472805, 4.08823569482289], "value": 4.138048275862068, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.922196078431372, 4.134349173553718, 4.1408281573498975, 3.9459368836291917, 4.159241164241163, 4.175521920668056, 4.203056722689077, 4.141469979296066, 3.9066015625000006, 4.185899581589959, 3.985358565737051, 3.899873294346979], "value": 4.1408281573498975, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.5638123515439424, 3.464284064665129, 3.417010250569476, 3.4249486301369876, 3.6465127582016987, 3.580322195704059, 3.4573214285714284, 3.421790193842645, 3.4325800915331794, 3.4376345933562433, 3.448999999999999, 3.4130375426621153], "value": 3.4376345933562433, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4662131715771247, 3.3513400335008376, 3.339941569282137, 3.3791216216216196, 3.6307622504537256, 3.5276984126984092, 3.3857106598984803, 3.3625546218487417, 3.3974108658743645, 3.346295986622072, 3.3858037225042334, 3.3557802013422853], "value": 3.3791216216216196, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.376944444444443, 5.759078694817664, 5.815891472868217, 5.628714821763602, 5.618061797752806, 5.715457142857146, 5.486352833638025, 5.760076775431862, 5.566929499072358, 5.566456400742115, 5.5569444444444445, 5.682821969696968], "value": 5.628714821763602, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.420596205962061, 5.408135135135131, 5.634098591549295, 5.652259887005654, 5.5274447513812195, 5.683650568181819, 5.619733146067416, 5.557958333333327, 5.571796657381619, 5.557708333333327, 5.588449720670393, 5.5408587257617725], "value": 5.571796657381619, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.20753401360544, 10.275034246575347, 10.48966783216784, 9.969784053156141, 10.001983333333325, 10.346017241379302, 10.206037414965989, 10.792895683453235, 10.06776845637585, 10.101902356902356, 10.002316666666665, 10.170355932203393], "value": 10.170355932203393, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.364430051813468, 9.855443349753688, 10.102171717171714, 9.902772277227726, 9.951492537313433, 9.52621428571429, 10.313402061855667, 9.85325123152709, 9.528238095238079, 9.663623188405792, 9.80487745098039, 10.475287958115176], "value": 9.855443349753688, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.156152416356882, 11.321377358490583, 11.239606741573029, 11.073708487084865, 11.070848708487073, 11.281672932330824, 11.11296296296295, 11.114425925925927, 11.238258426966285, 11.111759259259257, 11.239419475655442, 11.27868421052632], "value": 11.238258426966285, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.639175531914898, 10.695454545454522, 10.812675675675681, 10.367383419689114, 10.52750000000003, 10.697379679144404, 10.810837837837862, 10.934098360655751, 10.813918918918942, 10.754166666666675, 10.754301075268794, 10.587248677248667], "value": 10.754166666666675, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.450062240663886, 12.049257028112448, 12.659957805907156, 12.712351694915277, 12.931228448275855, 12.712033898305089, 12.822307692307685, 12.880000000000004, 13.100545851528391, 13.044391304347823, 12.876309012875538, 13.044652173913029], "value": 12.876309012875538, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.198109756097548, 12.048403614457852, 12.19682926829272, 12.197865853658518, 12.05403614457832, 12.423385093167708, 12.04993975903615, 12.274417177914085, 12.197957317073193, 11.980568862275433, 12.05274096385545, 12.199939024390249], "value": 12.19682926829272, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.495383141762474, 11.674143968871585, 11.542211538461562, 11.676945525291822, 11.908194444444437, 11.539057692307717, 11.955219123505955, 11.76709803921569, 11.452156488549633, 11.497375478927212, 11.631434108527145, 11.629127906976754], "value": 11.631434108527145, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.989532967032934, 11.428542857142872, 10.930573770491794, 11.363664772727258, 11.30076271186441, 10.874864130434775, 11.432171428571456, 11.051408839779008, 11.050662983425404, 11.178072625698324, 10.992609890109884, 11.23853932584269], "value": 11.178072625698324, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.079400000000001, 6.501849999999999, 6.6435, 6.558900000000001, 6.60145], "value": 6.5801750000000006, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8296.59, 8914.235, 8568.415, 8686.705, 8502.570000000002], "value": 8627.560000000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
10:45:48 INFO - Return code: 0
10:45:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
10:45:48 INFO - # TBPL SUCCESS #
10:45:48 INFO - Running post-action listener: _package_coverage_data
10:45:48 INFO - Running post-action listener: _resource_record_post_action
10:45:48 INFO - [mozharness: 2017-12-18 18:45:48.982560Z] Finished run-tests step (success)
10:45:48 INFO - Running post-run listener: _resource_record_post_run
10:45:49 INFO - Total resource usage - Wall time: 645s; CPU: 36.0%; Read bytes: 4055040; Write bytes: 307515392; Read time: 840; Write time: 202052
10:45:49 INFO - TinderboxPrint: CPU usage
36.4%
10:45:49 INFO - TinderboxPrint: I/O read bytes / time
4,055,040 / 840
10:45:49 INFO - TinderboxPrint: I/O write bytes / time
307,515,392 / 202,052
10:45:49 INFO - TinderboxPrint: CPU idle
3,220.5 (63.6%)
10:45:49 INFO - TinderboxPrint: CPU system
315.0 (6.2%)
10:45:49 INFO - TinderboxPrint: CPU user
1,511.4 (29.8%)
10:45:49 INFO - TinderboxPrint: Swap in / out
0 / 0
10:45:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 6660096; Read time: 0; Write time: 680
10:45:49 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
10:45:49 INFO - run-tests - Wall time: 629s; CPU: 37.0%; Read bytes: 4055040; Write bytes: 300855296; Read time: 840; Write time: 201372
10:45:49 INFO - Running post-run listener: _upload_blobber_files
10:45:49 INFO - Blob upload gear active.
10:45:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
10:45:49 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
10:45:49 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', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
10:45:49 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 Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
10:45:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:49 INFO - SNIMissingWarning
10:45:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:49 INFO - InsecurePlatformWarning
10:45:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:45:49 INFO - SubjectAltNameWarning
10:45:49 INFO - (blobuploader) - INFO - Open directory for files ...
10:45:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
10:45:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:45:49 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:45:49 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:49 INFO - InsecurePlatformWarning
10:45:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:45:50 INFO - SubjectAltNameWarning
10:45:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:50 INFO - InsecurePlatformWarning
10:45:50 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
10:45:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:45:50 INFO - (blobuploader) - INFO - Done attempting.
10:45:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
10:45:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:45:50 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:45:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:50 INFO - InsecurePlatformWarning
10:45:50 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:45:50 INFO - SubjectAltNameWarning
10:45:51 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:51 INFO - InsecurePlatformWarning
10:45:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
10:45:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:45:51 INFO - (blobuploader) - INFO - Done attempting.
10:45:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
10:45:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:45:51 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:45:51 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:51 INFO - InsecurePlatformWarning
10:45:52 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:45:52 INFO - SubjectAltNameWarning
10:45:54 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:54 INFO - InsecurePlatformWarning
10:45:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
10:45:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:45:54 INFO - (blobuploader) - INFO - Done attempting.
10:45:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
10:45:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
10:45:54 INFO - (blobuploader) - INFO - Uploading, attempt #1.
10:45:54 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:54 INFO - InsecurePlatformWarning
10:45:54 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
10:45:54 INFO - SubjectAltNameWarning
10:45:55 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
10:45:55 INFO - InsecurePlatformWarning
10:45:55 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
10:45:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
10:45:55 INFO - (blobuploader) - INFO - Done attempting.
10:45:55 INFO - (blobuploader) - INFO - Iteration through files over.
10:45:55 INFO - Return code: 0
10:45:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
10:45:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
10:45:55 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4150d151fd545443e8b26ebe9b462686874a150751b53331e2f75de79f1038da0c10695aa589689ff5a4526a7766ab8ff95883ac28676a5aa610953b364cd741", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/efbf2d14e059992b90a070e382f7332b6950f15ae2d676a67586c8018d2ec9c34b11fd4289ae1a93d7e1e0fb08142f87a5fc352653af3e01708a7587bedd3d01", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8e8a0a89da6b2e8a2fb31ed8d75f4f35e579a82d4ca764c06d412d5a7db79aada45c9dbbb105a19beb25b8737efdcae03fbe3ef83317101a4db0f5e50bef3eeb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/467dfd3076dcbe02115047bd55291bfad98e73ba342a345c6eb01860455727cd46032073511acaeacd934a5818d1feb37c673aedd9bf37c5e06345ab0976e106"}
10:45:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
10:45:55 INFO - Writing to file /builds/slave/test/properties/blobber_files
10:45:55 INFO - Contents:
10:45:55 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4150d151fd545443e8b26ebe9b462686874a150751b53331e2f75de79f1038da0c10695aa589689ff5a4526a7766ab8ff95883ac28676a5aa610953b364cd741", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/efbf2d14e059992b90a070e382f7332b6950f15ae2d676a67586c8018d2ec9c34b11fd4289ae1a93d7e1e0fb08142f87a5fc352653af3e01708a7587bedd3d01", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8e8a0a89da6b2e8a2fb31ed8d75f4f35e579a82d4ca764c06d412d5a7db79aada45c9dbbb105a19beb25b8737efdcae03fbe3ef83317101a4db0f5e50bef3eeb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/467dfd3076dcbe02115047bd55291bfad98e73ba342a345c6eb01860455727cd46032073511acaeacd934a5818d1feb37c673aedd9bf37c5e06345ab0976e106"}
10:45:55 INFO - Running post-run listener: copy_logs_to_upload_dir
10:45:55 INFO - Copying logs to upload dir...
10:45:55 INFO - mkdir: /builds/slave/test/build/upload/logs
10:45:55 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=707.167993
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 47 secs) (at 2017-12-18 10:45:55.457916) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-18 10:45:55.461203) =========
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=a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4150d151fd545443e8b26ebe9b462686874a150751b53331e2f75de79f1038da0c10695aa589689ff5a4526a7766ab8ff95883ac28676a5aa610953b364cd741", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/efbf2d14e059992b90a070e382f7332b6950f15ae2d676a67586c8018d2ec9c34b11fd4289ae1a93d7e1e0fb08142f87a5fc352653af3e01708a7587bedd3d01", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8e8a0a89da6b2e8a2fb31ed8d75f4f35e579a82d4ca764c06d412d5a7db79aada45c9dbbb105a19beb25b8737efdcae03fbe3ef83317101a4db0f5e50bef3eeb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/467dfd3076dcbe02115047bd55291bfad98e73ba342a345c6eb01860455727cd46032073511acaeacd934a5818d1feb37c673aedd9bf37c5e06345ab0976e106"}
build_url:https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011313
build_url: 'https://queue.taskcluster.net/v1/task/IkbY7NNsSOWAbs2IKlWjiw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/4150d151fd545443e8b26ebe9b462686874a150751b53331e2f75de79f1038da0c10695aa589689ff5a4526a7766ab8ff95883ac28676a5aa610953b364cd741", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/efbf2d14e059992b90a070e382f7332b6950f15ae2d676a67586c8018d2ec9c34b11fd4289ae1a93d7e1e0fb08142f87a5fc352653af3e01708a7587bedd3d01", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8e8a0a89da6b2e8a2fb31ed8d75f4f35e579a82d4ca764c06d412d5a7db79aada45c9dbbb105a19beb25b8737efdcae03fbe3ef83317101a4db0f5e50bef3eeb", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/467dfd3076dcbe02115047bd55291bfad98e73ba342a345c6eb01860455727cd46032073511acaeacd934a5818d1feb37c673aedd9bf37c5e06345ab0976e106"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-12-18 10:45:55.491642) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 10:45:55.492011) =========
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=a4ce256d2ec601f24a60a71e000001bc-1513621079.348921-1409380942
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004540
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-12-18 10:45:55.576787) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-18 10:45:55.577138) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-12-18 10:45:55.577514) =========
========= Total master_lag: 0.21 =========