builder: mozilla-central_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-024
starttime: 1511368354.72
results: success (0)
revision: 05a913c5cf3f9f9577d103352c6a593b8fa4fd60
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:34.720957) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:34.721403) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:34.721704) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:34.832875) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:34.833117) =========
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=41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-22 08:32:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 40.3M=0s
2017-11-22 08:32:34 (40.3 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.108270
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:34.960834) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:34.961214) =========
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=41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.022676
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:35.018266) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-22 08:32:35.018548) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 05a913c5cf3f9f9577d103352c6a593b8fa4fd60 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 05a913c5cf3f9f9577d103352c6a593b8fa4fd60 --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=41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-22 08:32:35,087 truncating revision to first 12 chars
2017-11-22 08:32:35,087 Setting DEBUG logging.
2017-11-22 08:32:35,088 attempt 1/10
2017-11-22 08:32:35,088 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/05a913c5cf3f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-22 08:32:36,204 unpacking tar archive at: mozilla-central-05a913c5cf3f/testing/mozharness/
program finished with exit code 0
elapsedTime=1.798210
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-22 08:32:36.834535) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:36.834811) =========
script_repo_revision: 05a913c5cf3f9f9577d103352c6a593b8fa4fd60
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:36.835148) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:36.835393) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-22 08:32:36.850227) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 33 secs) (at 2017-11-22 08:32:36.850493) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
08:32:37 INFO - MultiFileLogger online at 20171122 08:32:37 in /builds/slave/test
08:32:37 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
08:32:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
08:32:37 INFO - {'append_to_log': False,
08:32:37 INFO - 'base_work_dir': '/builds/slave/test',
08:32:37 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
08:32:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
08:32:37 INFO - 'branch': 'Firefox-Non-PGO',
08:32:37 INFO - 'buildbot_json_path': 'buildprops.json',
08:32:37 INFO - 'code_coverage': False,
08:32:37 INFO - 'config_files': ('talos/linux_config.py',),
08:32:37 INFO - 'default_actions': ('clobber',
08:32:37 INFO - 'read-buildbot-config',
08:32:37 INFO - 'download-and-extract',
08:32:37 INFO - 'populate-webroot',
08:32:37 INFO - 'create-virtualenv',
08:32:37 INFO - 'install',
08:32:37 INFO - 'setup-mitmproxy',
08:32:37 INFO - 'run-tests'),
08:32:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
08:32:37 INFO - 'disable_ccov_upload': False,
08:32:37 INFO - 'disable_stylo': False,
08:32:37 INFO - 'download_minidump_stackwalk': True,
08:32:37 INFO - 'download_symbols': 'ondemand',
08:32:37 INFO - 'e10s': False,
08:32:37 INFO - 'enable_stylo': False,
08:32:37 INFO - 'enable_webrender': False,
08:32:37 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
08:32:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
08:32:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
08:32:37 INFO - 'gecko_profile': False,
08:32:37 INFO - 'gecko_profile_interval': 0,
08:32:37 INFO - 'installer_path': 'installer.exe',
08:32:37 INFO - 'jsd_code_coverage': False,
08:32:37 INFO - 'log_level': 'info',
08:32:37 INFO - 'log_name': 'talos',
08:32:37 INFO - 'log_to_console': True,
08:32:37 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
08:32:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
08:32:37 INFO - 'opt_config_files': (),
08:32:37 INFO - 'pip_index': False,
08:32:37 INFO - 'suite': 'g4-e10s',
08:32:37 INFO - 'system_bits': '32',
08:32:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
08:32:37 INFO - 'title': 'talos-linux64-ix-024',
08:32:37 INFO - 'tooltool_cache': '/builds/tooltool_cache',
08:32:37 INFO - 'use_talos_json': True,
08:32:37 INFO - 'verify': 'False',
08:32:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
08:32:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
08:32:37 INFO - 'work_dir': 'build'}
08:32:37 INFO - [mozharness: 2017-11-22 16:32:37.061990Z] Running clobber step.
08:32:37 INFO - Running pre-action listener: _resource_record_pre_action
08:32:37 INFO - Running main action method: clobber
08:32:37 INFO - rmtree: /builds/slave/test/build
08:32:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
08:32:38 INFO - Running post-action listener: _resource_record_post_action
08:32:38 INFO - [mozharness: 2017-11-22 16:32:38.156287Z] Finished clobber step (success)
08:32:38 INFO - [mozharness: 2017-11-22 16:32:38.156392Z] Running read-buildbot-config step.
08:32:38 INFO - Running pre-action listener: _resource_record_pre_action
08:32:38 INFO - Running main action method: read_buildbot_config
08:32:38 INFO - Using buildbot properties:
08:32:38 INFO - {
08:32:38 INFO - "project": "",
08:32:38 INFO - "product": "firefox",
08:32:38 INFO - "who": "aciure@mozilla.com",
08:32:38 INFO - "installer_path": "public/build/target.tar.bz2",
08:32:38 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-e10s",
08:32:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
08:32:38 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-e10s",
08:32:38 INFO - "stage_platform": "linux64",
08:32:38 INFO - "basedir": "/builds/slave/test",
08:32:38 INFO - "buildnumber": 858,
08:32:38 INFO - "platform": "ubuntu64_hw",
08:32:38 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
08:32:38 INFO - "slavebuilddir": "test",
08:32:38 INFO - "taskId": "UjJf1SYCTfegvXS3yFwI2g",
08:32:38 INFO - "branch": "mozilla-central",
08:32:38 INFO - "script_repo_revision": "production",
08:32:38 INFO - "revision": "05a913c5cf3f9f9577d103352c6a593b8fa4fd60",
08:32:38 INFO - "slavename": "talos-linux64-ix-024",
08:32:38 INFO - "repo_path": "mozilla-central"
08:32:38 INFO - }
08:32:38 INFO - Finding installer, test and symbols from parent task.
08:32:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjJf1SYCTfegvXS3yFwI2g'}, attempt #1
08:32:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjJf1SYCTfegvXS3yFwI2g'}, attempt #1
08:32:39 INFO - Task dependencies: G3kTrmh9Q1aM9DgrXEzRdg
08:32:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg'}, attempt #1
08:32:39 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2
08:32:39 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.test_packages.json
08:32:39 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.crashreporter-symbols.zip
08:32:39 INFO - Running post-action listener: _resource_record_post_action
08:32:39 INFO - [mozharness: 2017-11-22 16:32:39.522641Z] Finished read-buildbot-config step (success)
08:32:39 INFO - [mozharness: 2017-11-22 16:32:39.522855Z] Running download-and-extract step.
08:32:39 INFO - Running pre-action listener: _resource_record_pre_action
08:32:39 INFO - Running main action method: download_and_extract
08:32:39 INFO - mkdir: /builds/slave/test/build/tests
08:32:39 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')]}
08:32:39 INFO - trying https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.test_packages.json
08:32:39 INFO - Downloading https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
08:32:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
08:32:40 INFO - Downloaded 989 bytes.
08:32:40 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
08:32:40 INFO - Using the following test package requirements:
08:32:40 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
08:32:40 INFO - u'common': [u'target.common.tests.zip'],
08:32:40 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
08:32:40 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
08:32:40 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
08:32:40 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
08:32:40 INFO - u'mozbase': [u'target.common.tests.zip'],
08:32:40 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
08:32:40 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
08:32:40 INFO - u'web-platform': [u'target.common.tests.zip',
08:32:40 INFO - u'target.web-platform.tests.tar.gz'],
08:32:40 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
08:32:40 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
08:32:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.common.tests.zip
08:32:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.common.tests.zip'}, attempt #1
08:32:40 INFO - Fetch https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.common.tests.zip into memory
08:32:41 INFO - Content-Length response header: 59063291
08:32:41 INFO - Bytes received: 59063291
08:32:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.talos.tests.zip
08:32:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.talos.tests.zip'}, attempt #1
08:32:52 INFO - Fetch https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.talos.tests.zip into memory
08:32:53 INFO - Content-Length response header: 18772503
08:32:53 INFO - Bytes received: 18772503
08:32:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:54 INFO - trying https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2
08:32:54 INFO - Downloading https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
08:32:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
08:32:55 INFO - Downloaded 62639634 bytes.
08:32:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2
08:32:55 INFO - mkdir: /builds/slave/test/properties
08:32:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
08:32:55 INFO - Writing to file /builds/slave/test/properties/build_url
08:32:55 INFO - Contents:
08:32:55 INFO - build_url:https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2
08:32:55 INFO - Running post-action listener: _resource_record_post_action
08:32:55 INFO - Running post-action listener: find_tests_for_verification
08:32:55 INFO - Running post-action listener: set_extra_try_arguments
08:32:55 INFO - [mozharness: 2017-11-22 16:32:55.520734Z] Finished download-and-extract step (success)
08:32:55 INFO - [mozharness: 2017-11-22 16:32:55.520827Z] Running populate-webroot step.
08:32:55 INFO - Running pre-action listener: _resource_record_pre_action
08:32:55 INFO - Running main action method: populate_webroot
08:32:55 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
08:32:55 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['tresize',
08:32:55 INFO - 'tcanvasmark']},
08:32:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
08:32:55 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['dromaeo_css',
08:32:55 INFO - 'kraken']},
08:32:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:32:55 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:32:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'tests': ['damp', 'tps']},
08:32:55 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['damp', 'tps']},
08:32:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
08:32:55 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['dromaeo_dom']},
08:32:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
08:32:55 INFO - 'glvideo',
08:32:55 INFO - 'displaylist_mutate']},
08:32:55 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['basic_compositor_video',
08:32:55 INFO - 'glvideo']},
08:32:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
08:32:55 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['ts_paint_webext',
08:32:55 INFO - 'tp5o_webext']},
08:32:55 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
08:32:55 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:55 INFO - 'talos_options': ['--mitmproxy',
08:32:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:55 INFO - '--firstNonBlankPaint'],
08:32:55 INFO - 'tests': ['tp6_google_heavy',
08:32:55 INFO - 'tp6_youtube_heavy',
08:32:55 INFO - 'tp6_amazon_heavy',
08:32:55 INFO - 'tp6_facebook_heavy']},
08:32:55 INFO - 'other-e10s': {'tests': ['a11yr',
08:32:55 INFO - 'ts_paint',
08:32:55 INFO - 'tpaint',
08:32:55 INFO - 'sessionrestore',
08:32:55 INFO - 'sessionrestore_many_windows',
08:32:55 INFO - 'sessionrestore_no_auto_restore',
08:32:55 INFO - 'tabpaint',
08:32:55 INFO - 'cpstartup']},
08:32:55 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['a11yr',
08:32:55 INFO - 'ts_paint',
08:32:55 INFO - 'tpaint',
08:32:55 INFO - 'sessionrestore',
08:32:55 INFO - 'sessionrestore_many_windows',
08:32:55 INFO - 'sessionrestore_no_auto_restore',
08:32:55 INFO - 'tabpaint',
08:32:55 INFO - 'cpstartup']},
08:32:55 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
08:32:55 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
08:32:55 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['perf_reftest_singletons']},
08:32:55 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['perf_reftest']},
08:32:55 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
08:32:55 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['speedometer']},
08:32:55 INFO - 'svgr-e10s': {'tests': ['tsvgx',
08:32:55 INFO - 'tsvgr_opacity',
08:32:55 INFO - 'tart',
08:32:55 INFO - 'tscrollx',
08:32:55 INFO - 'tsvg_static']},
08:32:55 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['tsvgx',
08:32:55 INFO - 'tsvgr_opacity',
08:32:55 INFO - 'tart',
08:32:55 INFO - 'tscrollx',
08:32:55 INFO - 'tsvg_static']},
08:32:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
08:32:55 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'talos_options': ['--disable-stylo'],
08:32:55 INFO - 'tests': ['tp5o']},
08:32:55 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:55 INFO - 'talos_options': ['--mitmproxy',
08:32:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:55 INFO - '--firstNonBlankPaint'],
08:32:55 INFO - 'tests': ['tp6_google',
08:32:55 INFO - 'tp6_youtube',
08:32:55 INFO - 'tp6_amazon',
08:32:55 INFO - 'tp6_facebook']},
08:32:55 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:55 INFO - 'talos_options': ['--disable-stylo',
08:32:55 INFO - '--mitmproxy',
08:32:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:55 INFO - '--firstNonBlankPaint'],
08:32:55 INFO - 'tests': ['tp6_google',
08:32:55 INFO - 'tp6_youtube',
08:32:55 INFO - 'tp6_amazon',
08:32:55 INFO - 'tp6_facebook']},
08:32:55 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:32:55 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:32:55 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:32:55 INFO - 'talos_options': ['--stylo-threads=1',
08:32:55 INFO - '--mitmproxy',
08:32:55 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:32:55 INFO - '--firstNonBlankPaint'],
08:32:55 INFO - 'tests': ['tp6_google',
08:32:55 INFO - 'tp6_youtube',
08:32:55 INFO - 'tp6_amazon',
08:32:55 INFO - 'tp6_facebook']},
08:32:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'talos_options': ['--xperf_path',
08:32:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:32:55 INFO - 'tests': ['tp5n']},
08:32:55 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:32:55 INFO - 'talos_options': ['--disable-stylo',
08:32:55 INFO - '--xperf_path',
08:32:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:32:55 INFO - 'tests': ['tp5n']}}}
08:32:55 INFO - Running post-action listener: _resource_record_post_action
08:32:55 INFO - [mozharness: 2017-11-22 16:32:55.539346Z] Finished populate-webroot step (success)
08:32:55 INFO - [mozharness: 2017-11-22 16:32:55.539439Z] Running create-virtualenv step.
08:32:55 INFO - Running pre-action listener: _resource_record_pre_action
08:32:55 INFO - Running main action method: create_virtualenv
08:32:55 INFO - Creating virtualenv /builds/slave/test/build/venv
08:32:55 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
08:32:55 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
08:32:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
08:32:55 INFO - Using real prefix '/usr'
08:32:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python
08:32:55 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
08:32:57 INFO - Installing setuptools, pip, wheel...done.
08:32:57 INFO - Return code: 0
08:32:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
08:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:32:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
08:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
08:32:57 INFO - Using env: {'DISPLAY': ':0',
08:32:57 INFO - 'HOME': '/home/cltbld',
08:32:57 INFO - 'LANG': 'en_US.UTF-8',
08:32:57 INFO - 'LANGUAGE': 'en_US:en',
08:32:57 INFO - 'LOGNAME': 'cltbld',
08:32:57 INFO - 'MAIL': '/var/mail/cltbld',
08:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:57 INFO - 'MOZ_NO_REMOTE': '1',
08:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:57 INFO - 'NO_EM_RESTART': '1',
08:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:57 INFO - 'PWD': '/builds/slave/test',
08:32:57 INFO - 'SHELL': '/bin/bash',
08:32:57 INFO - 'SHLVL': '1',
08:32:57 INFO - 'TERM': 'linux',
08:32:57 INFO - 'TMOUT': '86400',
08:32:57 INFO - 'USER': 'cltbld',
08:32:57 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:57 INFO - '_': '/tools/buildbot/bin/python'}
08:32:57 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
08:32:57 INFO - Return code: 0
08:32:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
08:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:32:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
08:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
08:32:57 INFO - Using env: {'DISPLAY': ':0',
08:32:57 INFO - 'HOME': '/home/cltbld',
08:32:57 INFO - 'LANG': 'en_US.UTF-8',
08:32:57 INFO - 'LANGUAGE': 'en_US:en',
08:32:57 INFO - 'LOGNAME': 'cltbld',
08:32:57 INFO - 'MAIL': '/var/mail/cltbld',
08:32:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:57 INFO - 'MOZ_NO_REMOTE': '1',
08:32:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:57 INFO - 'NO_EM_RESTART': '1',
08:32:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:57 INFO - 'PWD': '/builds/slave/test',
08:32:57 INFO - 'SHELL': '/bin/bash',
08:32:57 INFO - 'SHLVL': '1',
08:32:57 INFO - 'TERM': 'linux',
08:32:57 INFO - 'TMOUT': '86400',
08:32:57 INFO - 'USER': 'cltbld',
08:32:57 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:32:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:57 INFO - '_': '/tools/buildbot/bin/python'}
08:32:58 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:58 INFO - Collecting psutil>=3.1.1
08:32:59 INFO - Installing collected packages: psutil
08:32:59 INFO - Successfully installed psutil-3.1.1
08:32:59 INFO - Return code: 0
08:32:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
08:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:32:59 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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:32:59 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
08:32:59 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
08:32:59 INFO - Using env: {'DISPLAY': ':0',
08:32:59 INFO - 'HOME': '/home/cltbld',
08:32:59 INFO - 'LANG': 'en_US.UTF-8',
08:32:59 INFO - 'LANGUAGE': 'en_US:en',
08:32:59 INFO - 'LOGNAME': 'cltbld',
08:32:59 INFO - 'MAIL': '/var/mail/cltbld',
08:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:32:59 INFO - 'MOZ_NO_REMOTE': '1',
08:32:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:32:59 INFO - 'NO_EM_RESTART': '1',
08:32:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:32:59 INFO - 'PWD': '/builds/slave/test',
08:32:59 INFO - 'SHELL': '/bin/bash',
08:32:59 INFO - 'SHLVL': '1',
08:32:59 INFO - 'TERM': 'linux',
08:32:59 INFO - 'TMOUT': '86400',
08:32:59 INFO - 'USER': 'cltbld',
08:32:59 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:32:59 INFO - '_': '/tools/buildbot/bin/python'}
08:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple
08:32:59 INFO - Collecting mozsystemmonitor==0.3
08:33:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
08:33:00 INFO - Installing collected packages: mozsystemmonitor
08:33:01 INFO - Successfully installed mozsystemmonitor-0.3
08:33:01 INFO - Return code: 0
08:33:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
08:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:33:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
08:33:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
08:33:01 INFO - Using env: {'DISPLAY': ':0',
08:33:01 INFO - 'HOME': '/home/cltbld',
08:33:01 INFO - 'LANG': 'en_US.UTF-8',
08:33:01 INFO - 'LANGUAGE': 'en_US:en',
08:33:01 INFO - 'LOGNAME': 'cltbld',
08:33:01 INFO - 'MAIL': '/var/mail/cltbld',
08:33:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:01 INFO - 'MOZ_NO_REMOTE': '1',
08:33:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:01 INFO - 'NO_EM_RESTART': '1',
08:33:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:01 INFO - 'PWD': '/builds/slave/test',
08:33:01 INFO - 'SHELL': '/bin/bash',
08:33:01 INFO - 'SHLVL': '1',
08:33:01 INFO - 'TERM': 'linux',
08:33:01 INFO - 'TMOUT': '86400',
08:33:01 INFO - 'USER': 'cltbld',
08:33:01 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:01 INFO - '_': '/tools/buildbot/bin/python'}
08:33:01 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:01 INFO - Collecting jsonschema==2.5.1
08:33:02 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
08:33:02 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
08:33:03 INFO - Installing collected packages: functools32, jsonschema
08:33:03 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
08:33:03 INFO - Return code: 0
08:33:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
08:33: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')]}
08:33:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33: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')]}
08:33:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33: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', '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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:33: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', 'functools32==3.2.3-2'] in /builds/slave/test/build
08:33: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 functools32==3.2.3-2
08:33:03 INFO - Using env: {'DISPLAY': ':0',
08:33:03 INFO - 'HOME': '/home/cltbld',
08:33:03 INFO - 'LANG': 'en_US.UTF-8',
08:33:03 INFO - 'LANGUAGE': 'en_US:en',
08:33:03 INFO - 'LOGNAME': 'cltbld',
08:33:03 INFO - 'MAIL': '/var/mail/cltbld',
08:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:03 INFO - 'MOZ_NO_REMOTE': '1',
08:33:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:03 INFO - 'NO_EM_RESTART': '1',
08:33:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:03 INFO - 'PWD': '/builds/slave/test',
08:33:03 INFO - 'SHELL': '/bin/bash',
08:33:03 INFO - 'SHLVL': '1',
08:33:03 INFO - 'TERM': 'linux',
08:33:03 INFO - 'TMOUT': '86400',
08:33:03 INFO - 'USER': 'cltbld',
08:33:03 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:03 INFO - '_': '/tools/buildbot/bin/python'}
08:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
08:33:04 INFO - Return code: 0
08:33:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
08:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:33:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
08:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
08:33:04 INFO - Using env: {'DISPLAY': ':0',
08:33:04 INFO - 'HOME': '/home/cltbld',
08:33:04 INFO - 'LANG': 'en_US.UTF-8',
08:33:04 INFO - 'LANGUAGE': 'en_US:en',
08:33:04 INFO - 'LOGNAME': 'cltbld',
08:33:04 INFO - 'MAIL': '/var/mail/cltbld',
08:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:04 INFO - 'MOZ_NO_REMOTE': '1',
08:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:04 INFO - 'NO_EM_RESTART': '1',
08:33:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:04 INFO - 'PWD': '/builds/slave/test',
08:33:04 INFO - 'SHELL': '/bin/bash',
08:33:04 INFO - 'SHLVL': '1',
08:33:04 INFO - 'TERM': 'linux',
08:33:04 INFO - 'TMOUT': '86400',
08:33:04 INFO - 'USER': 'cltbld',
08:33:04 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:04 INFO - '_': '/tools/buildbot/bin/python'}
08:33:04 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:04 INFO - Collecting blobuploader==1.2.4
08:33:05 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
08:33:06 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
08:33:07 INFO - Installing collected packages: requests, docopt, blobuploader
08:33:07 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
08:33:07 INFO - Return code: 0
08:33:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:33:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33:07 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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:33:07 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
08:33:07 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
08:33:07 INFO - Using env: {'DISPLAY': ':0',
08:33:07 INFO - 'HOME': '/home/cltbld',
08:33:07 INFO - 'LANG': 'en_US.UTF-8',
08:33:07 INFO - 'LANGUAGE': 'en_US:en',
08:33:07 INFO - 'LOGNAME': 'cltbld',
08:33:07 INFO - 'MAIL': '/var/mail/cltbld',
08:33:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:07 INFO - 'MOZ_NO_REMOTE': '1',
08:33:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:07 INFO - 'NO_EM_RESTART': '1',
08:33:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:07 INFO - 'PWD': '/builds/slave/test',
08:33:07 INFO - 'SHELL': '/bin/bash',
08:33:07 INFO - 'SHLVL': '1',
08:33:07 INFO - 'TERM': 'linux',
08:33:07 INFO - 'TMOUT': '86400',
08:33:07 INFO - 'USER': 'cltbld',
08:33:07 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:07 INFO - '_': '/tools/buildbot/bin/python'}
08:33:08 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:33:08 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:33:09 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:33:10 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:33:11 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:33:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
08:33:11 INFO - Running setup.py install for manifestparser: started
08:33:11 INFO - Running setup.py install for manifestparser: finished with status 'done'
08:33:11 INFO - Running setup.py install for mozcrash: started
08:33:12 INFO - Running setup.py install for mozcrash: finished with status 'done'
08:33:12 INFO - Running setup.py install for mozdebug: started
08:33:12 INFO - Running setup.py install for mozdebug: finished with status 'done'
08:33:12 INFO - Running setup.py install for mozdevice: started
08:33:12 INFO - Running setup.py install for mozdevice: finished with status 'done'
08:33:12 INFO - Running setup.py install for mozfile: started
08:33:12 INFO - Running setup.py install for mozfile: finished with status 'done'
08:33:12 INFO - Running setup.py install for mozhttpd: started
08:33:12 INFO - Running setup.py install for mozhttpd: finished with status 'done'
08:33:12 INFO - Running setup.py install for mozinfo: started
08:33:13 INFO - Running setup.py install for mozinfo: finished with status 'done'
08:33:13 INFO - Running setup.py install for mozInstall: started
08:33:13 INFO - Running setup.py install for mozInstall: finished with status 'done'
08:33:13 INFO - Running setup.py install for mozleak: started
08:33:13 INFO - Running setup.py install for mozleak: finished with status 'done'
08:33:13 INFO - Running setup.py install for mozlog: started
08:33:13 INFO - Running setup.py install for mozlog: finished with status 'done'
08:33:13 INFO - Running setup.py install for moznetwork: started
08:33:14 INFO - Running setup.py install for moznetwork: finished with status 'done'
08:33:14 INFO - Running setup.py install for mozprocess: started
08:33:14 INFO - Running setup.py install for mozprocess: finished with status 'done'
08:33:14 INFO - Running setup.py install for mozprofile: started
08:33:14 INFO - Running setup.py install for mozprofile: finished with status 'done'
08:33:14 INFO - Running setup.py install for mozrunner: started
08:33:14 INFO - Running setup.py install for mozrunner: finished with status 'done'
08:33:14 INFO - Running setup.py install for mozscreenshot: started
08:33:14 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
08:33:14 INFO - Running setup.py install for moztest: started
08:33:15 INFO - Running setup.py install for moztest: finished with status 'done'
08:33:15 INFO - Running setup.py install for mozversion: started
08:33:15 INFO - Running setup.py install for mozversion: finished with status 'done'
08:33:15 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
08:33:15 INFO - Return code: 0
08:33:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:33:15 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')]}
08:33:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33:15 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')]}
08:33:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33:15 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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:33:15 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
08:33:15 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
08:33:15 INFO - Using env: {'DISPLAY': ':0',
08:33:15 INFO - 'HOME': '/home/cltbld',
08:33:15 INFO - 'LANG': 'en_US.UTF-8',
08:33:15 INFO - 'LANGUAGE': 'en_US:en',
08:33:15 INFO - 'LOGNAME': 'cltbld',
08:33:15 INFO - 'MAIL': '/var/mail/cltbld',
08:33:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:15 INFO - 'MOZ_NO_REMOTE': '1',
08:33:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:15 INFO - 'NO_EM_RESTART': '1',
08:33:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:15 INFO - 'PWD': '/builds/slave/test',
08:33:15 INFO - 'SHELL': '/bin/bash',
08:33:15 INFO - 'SHLVL': '1',
08:33:15 INFO - 'TERM': 'linux',
08:33:15 INFO - 'TMOUT': '86400',
08:33:15 INFO - 'USER': 'cltbld',
08:33:15 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:15 INFO - '_': '/tools/buildbot/bin/python'}
08:33:16 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:33:16 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))
08:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:33:16 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))
08:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:33:16 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))
08:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:33:16 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))
08:33:16 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:33:17 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))
08:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:33:17 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))
08:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:33:17 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))
08:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:33:17 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))
08:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:33:17 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))
08:33:17 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:33:18 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))
08:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:33:18 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))
08:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:33:18 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))
08:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:33:18 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))
08:33:18 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:33:19 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))
08:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:33:19 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))
08:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:33:19 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))
08:33:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:33:19 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))
08:33:19 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
08:33:20 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
08:33:20 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))
08:33:20 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))
08:33:20 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))
08:33:20 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))
08:33:20 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))
08:33:20 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))
08:33:20 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
08:33:21 INFO - Installing collected packages: six, blessings
08:33:21 INFO - Successfully installed blessings-1.6 six-1.10.0
08:33:21 INFO - Return code: 0
08:33:21 INFO - Done creating virtualenv /builds/slave/test/build/venv.
08:33:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:33:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:33:22 INFO - Reading from file tmpfile_stdout
08:33:22 INFO - Reading from file tmpfile_stderr
08:33:22 INFO - Current package versions:
08:33:22 INFO - blessings == 1.6
08:33:22 INFO - blobuploader == 1.2.4
08:33:22 INFO - docopt == 0.6.1
08:33:22 INFO - functools32 == 3.2.3.post2
08:33:22 INFO - jsonschema == 2.5.1
08:33:22 INFO - manifestparser == 1.2
08:33:22 INFO - mozInstall == 1.14
08:33:22 INFO - mozcrash == 1.0
08:33:22 INFO - mozdebug == 0.1
08:33:22 INFO - mozdevice == 0.51
08:33:22 INFO - mozfile == 1.2
08:33:22 INFO - mozhttpd == 0.7
08:33:22 INFO - mozinfo == 0.10
08:33:22 INFO - mozleak == 0.1
08:33:22 INFO - mozlog == 3.5
08:33:22 INFO - moznetwork == 0.27
08:33:22 INFO - mozprocess == 0.25
08:33:22 INFO - mozprofile == 0.29
08:33:22 INFO - mozrunner == 6.13
08:33:22 INFO - mozscreenshot == 0.1
08:33:22 INFO - mozsystemmonitor == 0.3
08:33:22 INFO - moztest == 0.8
08:33:22 INFO - mozversion == 1.4
08:33:22 INFO - psutil == 3.1.1
08:33:22 INFO - requests == 1.2.3
08:33:22 INFO - six == 1.10.0
08:33:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:33:22 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')]}
08:33:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33:22 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')]}
08:33:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33:22 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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:33:22 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
08:33:22 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
08:33:22 INFO - Using env: {'DISPLAY': ':0',
08:33:22 INFO - 'HOME': '/home/cltbld',
08:33:22 INFO - 'LANG': 'en_US.UTF-8',
08:33:22 INFO - 'LANGUAGE': 'en_US:en',
08:33:22 INFO - 'LOGNAME': 'cltbld',
08:33:22 INFO - 'MAIL': '/var/mail/cltbld',
08:33:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:22 INFO - 'MOZ_NO_REMOTE': '1',
08:33:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:22 INFO - 'NO_EM_RESTART': '1',
08:33:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:22 INFO - 'PWD': '/builds/slave/test',
08:33:22 INFO - 'SHELL': '/bin/bash',
08:33:22 INFO - 'SHLVL': '1',
08:33:22 INFO - 'TERM': 'linux',
08:33:22 INFO - 'TMOUT': '86400',
08:33:22 INFO - 'USER': 'cltbld',
08:33:22 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:22 INFO - '_': '/tools/buildbot/bin/python'}
08:33:22 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:22 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))
08:33:22 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))
08:33:22 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))
08:33:22 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))
08:33:22 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))
08:33:22 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))
08:33:22 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))
08:33:22 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))
08:33:22 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))
08:33:22 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
08:33:23 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:33:24 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
08:33:25 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))
08:33:25 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))
08:33:25 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))
08:33:25 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))
08:33:26 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
08:33:26 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:33:27 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
08:33:27 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:33:28 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
08:33:28 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:33:29 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
08:33:29 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
08:33:29 INFO - Found existing installation: requests 1.2.3
08:33:29 INFO - Uninstalling requests-1.2.3:
08:33:29 INFO - Successfully uninstalled requests-1.2.3
08:33:29 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
08:33:29 INFO - Return code: 0
08:33:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
08:33:29 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')]}
08:33:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:33:29 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')]}
08:33:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:33:29 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 0x1262d50>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1241a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13e5460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, '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': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406', '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
08:33:29 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
08:33:29 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
08:33:29 INFO - Using env: {'DISPLAY': ':0',
08:33:29 INFO - 'HOME': '/home/cltbld',
08:33:29 INFO - 'LANG': 'en_US.UTF-8',
08:33:29 INFO - 'LANGUAGE': 'en_US:en',
08:33:29 INFO - 'LOGNAME': 'cltbld',
08:33:29 INFO - 'MAIL': '/var/mail/cltbld',
08:33:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:29 INFO - 'MOZ_NO_REMOTE': '1',
08:33:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:29 INFO - 'NO_EM_RESTART': '1',
08:33:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:29 INFO - 'PWD': '/builds/slave/test',
08:33:29 INFO - 'SHELL': '/bin/bash',
08:33:29 INFO - 'SHLVL': '1',
08:33:29 INFO - 'TERM': 'linux',
08:33:29 INFO - 'TMOUT': '86400',
08:33:29 INFO - 'USER': 'cltbld',
08:33:29 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:29 INFO - '_': '/tools/buildbot/bin/python'}
08:33:30 INFO - Ignoring indexes: https://pypi.python.org/simple
08:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
08:33:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
08:33:30 INFO - Return code: 0
08:33:30 INFO - Running post-action listener: _resource_record_post_action
08:33:30 INFO - Running post-action listener: _start_resource_monitoring
08:33:30 INFO - Starting resource monitoring.
08:33:30 INFO - [mozharness: 2017-11-22 16:33:30.266669Z] Finished create-virtualenv step (success)
08:33:30 INFO - [mozharness: 2017-11-22 16:33:30.267181Z] Running install step.
08:33:30 INFO - Running pre-action listener: _resource_record_pre_action
08:33:30 INFO - Running main action method: install
08:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:33:30 INFO - Reading from file tmpfile_stdout
08:33:30 INFO - Reading from file tmpfile_stderr
08:33:30 INFO - Detecting whether we're running mozinstall >=1.0...
08:33:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
08:33:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
08:33:31 INFO - Reading from file tmpfile_stdout
08:33:31 INFO - Output received:
08:33:31 INFO - Usage: mozinstall [options] installer
08:33:31 INFO - Options:
08:33:31 INFO - -h, --help show this help message and exit
08:33:31 INFO - -d DEST, --destination=DEST
08:33:31 INFO - Directory to install application into. [default:
08:33:31 INFO - "/builds/slave/test"]
08:33:31 INFO - --app=APP Application being installed. [default: firefox]
08:33:31 INFO - mkdir: /builds/slave/test/build/application
08:33:31 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']
08:33:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
08:33:49 INFO - Reading from file tmpfile_stdout
08:33:49 INFO - Output received:
08:33:49 INFO - /builds/slave/test/build/application/firefox/firefox
08:33:49 INFO - Running post-action listener: _resource_record_post_action
08:33:49 INFO - [mozharness: 2017-11-22 16:33:49.253485Z] Finished install step (success)
08:33:49 INFO - [mozharness: 2017-11-22 16:33:49.253590Z] Running setup-mitmproxy step.
08:33:49 INFO - Running pre-action listener: _resource_record_pre_action
08:33:49 INFO - Running main action method: setup_mitmproxy
08:33:49 INFO - Skipping: mitmproxy is not required
08:33:49 INFO - Running post-action listener: _resource_record_post_action
08:33:49 INFO - [mozharness: 2017-11-22 16:33:49.254049Z] Finished setup-mitmproxy step (success)
08:33:49 INFO - [mozharness: 2017-11-22 16:33:49.254138Z] Running run-tests step.
08:33:49 INFO - Running pre-action listener: _resource_record_pre_action
08:33:49 INFO - Running pre-action listener: _set_gcov_prefix
08:33:49 INFO - Running main action method: run_tests
08:33:49 WARNING - Try message not found.
08:33:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
08:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
08:33:49 INFO - Python 2.7.3
08:33:49 INFO - Return code: 0
08:33:49 INFO - grabbing minidump binary from tooltool
08:33: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')]}
08:33:49 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 0x13e2600>, '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 0x13e3500>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13e3990>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
08:33:49 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
08:33:49 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
08:33:49 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
08:33:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
08:33:49 INFO - Return code: 0
08:33:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
08:33:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
08:33:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
08:33:49 INFO - ENV: RUST_BACKTRACE is now full
08:33:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
08:33:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
08:33:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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
08:33:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-024 --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
08:33:49 INFO - Using env: {'DISPLAY': ':0',
08:33:49 INFO - 'HOME': '/home/cltbld',
08:33:49 INFO - 'LANG': 'en_US.UTF-8',
08:33:49 INFO - 'LANGUAGE': 'en_US:en',
08:33:49 INFO - 'LOGNAME': 'cltbld',
08:33:49 INFO - 'MAIL': '/var/mail/cltbld',
08:33:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:33:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:33:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:49 INFO - 'MOZ_NO_REMOTE': '1',
08:33:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:33:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:49 INFO - 'NO_EM_RESTART': '1',
08:33:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:49 INFO - 'PWD': '/builds/slave/test',
08:33:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:33:49 INFO - 'RUST_BACKTRACE': 'full',
08:33:49 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:33:49 INFO - 'SHELL': '/bin/bash',
08:33:49 INFO - 'SHLVL': '1',
08:33:49 INFO - 'TERM': 'linux',
08:33:49 INFO - 'TMOUT': '86400',
08:33:49 INFO - 'USER': 'cltbld',
08:33:49 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:49 INFO - '_': '/tools/buildbot/bin/python'}
08:33:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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
08:33:49 INFO - mozversion application_buildid: 20171122154047
08:33:49 INFO - mozversion application_changeset: 05a913c5cf3f9f9577d103352c6a593b8fa4fd60
08:33:49 INFO - mozversion application_display_name: Nightly
08:33:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
08:33:49 INFO - mozversion application_name: Firefox
08:33:49 INFO - mozversion application_remotingname: firefox
08:33:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
08:33:49 INFO - mozversion application_vendor: Mozilla
08:33:49 INFO - mozversion application_version: 59.0a1
08:33:49 INFO - mozversion platform_buildid: 20171122154047
08:33:49 INFO - mozversion platform_changeset: 05a913c5cf3f9f9577d103352c6a593b8fa4fd60
08:33:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
08:33:49 INFO - mozversion platform_version: 59.0a1
08:33:49 INFO - using testdate: 1511368429
08:33:49 INFO - actual date: 1511368429
08:33:49 INFO - starting webserver on 'localhost:59351'
08:33:49 INFO - SUITE-START | Running 3 tests
08:33:49 INFO - TEST-START | basic_compositor_video
08:33:49 INFO - Initialising browser for basic_compositor_video test...
08:33:49 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
08:33:49 INFO - Installing Add-ons
08:33:49 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59351/getInfo.html -profile /tmp/tmp9WElzw/profile
08:33:49 INFO - TEST-INFO | started process 14825 (/builds/slave/test/build/application/firefox/firefox http://localhost:59351/getInfo.html)
08:33:55 INFO - TEST-INFO | 14825: exit 0
08:33:55 INFO - Browser initialized.
08:33:55 INFO - Running cycle 1/1 for basic_compositor_video test...
08:33:55 INFO - Using env: {'DISPLAY': ':0',
08:33:55 INFO - 'HOME': '/home/cltbld',
08:33:55 INFO - 'JSGC_DISABLE_POISONING': '1',
08:33:55 INFO - 'LANG': 'en_US.UTF-8',
08:33:55 INFO - 'LANGUAGE': 'en_US:en',
08:33:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:33:55 INFO - 'LOGNAME': 'cltbld',
08:33:55 INFO - 'MAIL': '/var/mail/cltbld',
08:33:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:33:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:33:55 INFO - 'MOZ_CRASHREPORTER': '1',
08:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:33:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:33:55 INFO - 'MOZ_NO_REMOTE': '1',
08:33:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:33:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:33:55 INFO - 'NO_EM_RESTART': '1',
08:33:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:33:55 INFO - 'PWD': '/builds/slave/test',
08:33:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:33:55 INFO - 'RUST_BACKTRACE': 'full',
08:33:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:33:55 INFO - 'SHELL': '/bin/bash',
08:33:55 INFO - 'SHLVL': '1',
08:33:55 INFO - 'TERM': 'linux',
08:33:55 INFO - 'TMOUT': '86400',
08:33:55 INFO - 'USER': 'cltbld',
08:33:55 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:33:55 INFO - '_': '/tools/buildbot/bin/python'}
08:33:55 INFO - TEST-INFO | started process 15097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9WElzw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
08:33:56 INFO - PID 15097 |
08:33:56 INFO - PID 15097 | (/builds/slave/test/build/application/firefox/firefox:15148): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:33:56 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6896537162162162 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.673047658862876 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.765129411764706 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7471746724890824 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.02738255033557 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.193679245283019 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.4768366164542295 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.468214904679376 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.683276515151516 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.36268096514745 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 10.384117647058819 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.176330275229352 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.450935114503805 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.699705882352948 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.661350210970456 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050150602409621 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.45446564885496 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.992939560439542 ms/frame
08:34:42 INFO - PID 15097 |
08:34:42 INFO - PID 15097 | Cycle 1(1): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:35:28 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827762198541782 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6785570469798654 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7775473933649288 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7351214223764104 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.050094466936571 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.00815631262525 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.585531660692951 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4915445026178054 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.61826779026217 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.555749999999999 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 10.137652027027023 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.217004608294932 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.237677902621733 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.871983695652172 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659324894514777 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.906190476190464 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.539884615384626 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.75438172043012 ms/frame
08:35:28 INFO - PID 15097 |
08:35:28 INFO - PID 15097 | Cycle 1(2): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:36:14 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810728291316526 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6676374999999999 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7427293844367018 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7084756618274979 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.190579608938547 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1763569937369525 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.5680856123662332 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.496555944055942 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.671994328922496 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683437499999999 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 9.646945337620581 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.666086956521726 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.195951492537311 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754865591397882 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.349567901234566 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.83760355029586 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583108108108114 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.24339887640452 ms/frame
08:36:14 INFO - PID 15097 |
08:36:14 INFO - PID 15097 | Cycle 1(3): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:37:00 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6826724621424567 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6717460317460313 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7807566765578633 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.767672261484099 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.043349056603772 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.2210970464135045 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.5304117647058826 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.48477351916376 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.803288201160547 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.363431635388734 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 9.617596153846161 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.350046728971963 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.452595419847334 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930737704918016 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.349506172839508 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.910565476190511 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.767470588235302 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.114916666666653 ms/frame
08:37:00 INFO - PID 15097 |
08:37:00 INFO - PID 15097 | Cycle 1(4): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:37:46 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819254484304935 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6724372909698997 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7463940628637953 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.724650862068965 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8873186528497414 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9539525691699584 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.5135245901639336 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.443218588640279 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.727356870229005 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.586759776536317 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 9.74262987012987 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 10.209132653061214 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.630135658914732 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.99002747252746 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.713728813559301 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050963855421681 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.237434456928828 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.874048913043502 ms/frame
08:37:46 INFO - PID 15097 |
08:37:46 INFO - PID 15097 | Cycle 1(5): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:38:33 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6792837157246783 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6714243943191314 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7546695906432748 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7430400696864112 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1732962447844235 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.151037344398342 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.4928870779976733 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4376975945017145 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.803655705996127 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.603613445378156 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 9.585702875399363 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.713373786407757 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.322075471698122 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.991016483516473 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.501791666666668 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.906726190476201 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.71875 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.175642458100572 ms/frame
08:38:33 INFO - PID 15097 |
08:38:33 INFO - PID 15097 | Cycle 1(6): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:39:19 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.683588664421998 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.671127819548872 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7493702623906706 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7100940170940175 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093949522510232 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.143167701863353 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.4765758980301293 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.432281303602059 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6400469924811985 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.51169421487604 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 9.772508143322476 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.618028846153843 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.494980842911886 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.050745856353577 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.502145833333332 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.20128048780489 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.631065891472877 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050552486187854 ms/frame
08:39:19 INFO - PID 15097 |
08:39:19 INFO - PID 15097 | Cycle 1(7): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:40:05 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6829136287156479 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687989991659715 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.79431858936043 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.783213012477718 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.043571428571428 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.212842105263158 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.533739693757362 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4665164644714044 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704068441064639 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.666458923512754 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 9.616137820512822 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.136529680365287 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.628565891472862 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.05046961325968 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.769765957446825 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.201402439024383 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.905416666666671 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.17773743016761 ms/frame
08:40:05 INFO - PID 15097 |
08:40:05 INFO - PID 15097 | Cycle 1(8): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:40:51 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819030269058295 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6672958333333334 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7609536384976525 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7445771578029647 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.179672701949859 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.202899159663866 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.677524509803922 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5471365248226934 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.673015122873348 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.540609418282548 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 10.003033333333333 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.572942583732052 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.280526315789457 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.870461956521744 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.400371900826444 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.770705882352923 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.322849056603774 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.756424731182793 ms/frame
08:40:51 INFO - PID 15097 |
08:40:51 INFO - PID 15097 | Cycle 1(9): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:41:37 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.688736634777715 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6697871452420707 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7498309037900874 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7413315926892943 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.065386178861788 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.15036307053942 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.5422609208972826 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4554231433506084 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.662433962264151 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6507062146892615 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 9.839311475409842 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.710485436893189 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.542211538461547 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.050110497237567 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.504395833333335 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.840118343195286 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.495919540229876 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.054033149171264 ms/frame
08:41:37 INFO - PID 15097 |
08:41:37 INFO - PID 15097 | Cycle 1(10): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:42:23 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6831099270891756 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6718045112781952 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7900536992840097 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.757614235500878 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.244172560113153 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.091584867075665 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.5345524146054172 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4195555555555597 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640037593984962 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.715057142857144 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 10.241552901023896 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.616298076923071 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.629263565891481 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.114222222222208 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346028806584359 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.767058823529421 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.408269961977187 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.990082417582416 ms/frame
08:42:23 INFO - PID 15097 |
08:42:23 INFO - PID 15097 | Cycle 1(11): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:43:10 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6858258426966293 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.673761506276151 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7580814294083187 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7248362068965513 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033676075268818 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.228847780126852 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_startup = 3.654549330085262 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5476595744680868 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.769548076923079 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.511363636363637 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_startup = 10.275719178082198 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.480p.60fps.webm_scale_2_inclip = 9.529261904761906 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.409657794676798 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930928961748638 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.659092827004196 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.838047337278134 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.814625984251972 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050441988950261 ms/frame
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | Cycle 1(12): loaded http://localhost:59351/tests/video/video_playback.html (next: http://localhost:59351/tests/video/video_playback.html)
08:43:10 INFO - PID 15097 | __start_tp_report
08:43:10 INFO - PID 15097 | _x_x_mozilla_page_load
08:43:10 INFO - PID 15097 | _x_x_mozilla_page_load_details
08:43:10 INFO - PID 15097 | |i|pagename|runs|
08:43:10 INFO - PID 15097 | |0;240p.120fps.mp4_scale_1_startup;1.6896537162162162;1.6827762198541782;1.6810728291316526;1.6826724621424567;1.6819254484304935;1.6792837157246783;1.683588664421998;1.6829136287156479;1.6819030269058295;1.688736634777715;1.6831099270891756;1.6858258426966293
08:43:10 INFO - PID 15097 | |1;240p.120fps.mp4_scale_1_inclip;1.673047658862876;1.6785570469798654;1.6676374999999999;1.6717460317460313;1.6724372909698997;1.6714243943191314;1.671127819548872;1.6687989991659715;1.6672958333333334;1.6697871452420707;1.6718045112781952;1.673761506276151
08:43:10 INFO - PID 15097 | |2;240p.120fps.mp4_scale_1.1_startup;1.765129411764706;1.7775473933649288;1.7427293844367018;1.7807566765578633;1.7463940628637953;1.7546695906432748;1.7493702623906706;1.79431858936043;1.7609536384976525;1.7498309037900874;1.7900536992840097;1.7580814294083187
08:43:10 INFO - PID 15097 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7471746724890824;1.7351214223764104;1.7084756618274979;1.767672261484099;1.724650862068965;1.7430400696864112;1.7100940170940175;1.783213012477718;1.7445771578029647;1.7413315926892943;1.757614235500878;1.7248362068965513
08:43:10 INFO - PID 15097 | |4;240p.120fps.mp4_scale_2_startup;4.02738255033557;4.050094466936571;4.190579608938547;4.043349056603772;3.8873186528497414;4.1732962447844235;4.093949522510232;4.043571428571428;4.179672701949859;4.065386178861788;4.244172560113153;4.033676075268818
08:43:10 INFO - PID 15097 | |5;240p.120fps.mp4_scale_2_inclip;4.193679245283019;4.00815631262525;4.1763569937369525;4.2210970464135045;3.9539525691699584;4.151037344398342;4.143167701863353;4.212842105263158;4.202899159663866;4.15036307053942;4.091584867075665;4.228847780126852
08:43:10 INFO - PID 15097 | |6;480p.60fps.webm_scale_1_startup;3.4768366164542295;3.585531660692951;3.5680856123662332;3.5304117647058826;3.5135245901639336;3.4928870779976733;3.4765758980301293;3.533739693757362;3.677524509803922;3.5422609208972826;3.5345524146054172;3.654549330085262
08:43:10 INFO - PID 15097 | |7;480p.60fps.webm_scale_1_inclip;3.468214904679376;3.4915445026178054;3.496555944055942;3.48477351916376;3.443218588640279;3.4376975945017145;3.432281303602059;3.4665164644714044;3.5471365248226934;3.4554231433506084;3.4195555555555597;3.5476595744680868
08:43:10 INFO - PID 15097 | |8;480p.60fps.webm_scale_1.1_startup;5.683276515151516;5.61826779026217;5.671994328922496;5.803288201160547;5.727356870229005;5.803655705996127;5.6400469924811985;5.704068441064639;5.673015122873348;5.662433962264151;5.640037593984962;5.769548076923079
08:43:10 INFO - PID 15097 | |9;480p.60fps.webm_scale_1.1_inclip;5.36268096514745;5.555749999999999;5.683437499999999;5.363431635388734;5.586759776536317;5.603613445378156;5.51169421487604;5.666458923512754;5.540609418282548;5.6507062146892615;5.715057142857144;5.511363636363637
08:43:10 INFO - PID 15097 | |10;480p.60fps.webm_scale_2_startup;10.384117647058819;10.137652027027023;9.646945337620581;9.617596153846161;9.74262987012987;9.585702875399363;9.772508143322476;9.616137820512822;10.003033333333333;9.839311475409842;10.241552901023896;10.275719178082198
08:43:10 INFO - PID 15097 | |11;480p.60fps.webm_scale_2_inclip;9.176330275229352;9.217004608294932;9.666086956521726;9.350046728971963;10.209132653061214;9.713373786407757;9.618028846153843;9.136529680365287;9.572942583732052;9.710485436893189;9.616298076923071;9.529261904761906
08:43:10 INFO - PID 15097 | |12;1080p.60fps.mp4_scale_1_startup;11.450935114503805;11.237677902621733;11.195951492537311;11.452595419847334;11.630135658914732;11.322075471698122;11.494980842911886;11.628565891472862;11.280526315789457;11.542211538461547;11.629263565891481;11.409657794676798
08:43:10 INFO - PID 15097 | |13;1080p.60fps.mp4_scale_1_inclip;10.699705882352948;10.871983695652172;10.754865591397882;10.930737704918016;10.99002747252746;10.991016483516473;11.050745856353577;11.05046961325968;10.870461956521744;11.050110497237567;11.114222222222208;10.930928961748638
08:43:10 INFO - PID 15097 | |14;1080p.60fps.mp4_scale_1.1_startup;12.661350210970456;12.659324894514777;12.349567901234566;12.349506172839508;12.713728813559301;12.501791666666668;12.502145833333332;12.769765957446825;12.400371900826444;12.504395833333335;12.346028806584359;12.659092827004196
08:43:10 INFO - PID 15097 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.050150602409621;11.906190476190464;11.83760355029586;11.910565476190511;12.050963855421681;11.906726190476201;12.20128048780489;12.201402439024383;11.770705882352923;11.840118343195286;11.767058823529421;11.838047337278134
08:43:10 INFO - PID 15097 | |16;1080p.60fps.mp4_scale_2_startup;11.45446564885496;11.539884615384626;11.583108108108114;11.767470588235302;11.237434456928828;11.71875;11.631065891472877;11.905416666666671;11.322849056603774;11.495919540229876;11.408269961977187;11.814625984251972
08:43:10 INFO - PID 15097 | |17;1080p.60fps.mp4_scale_2_inclip;10.992939560439542;10.75438172043012;11.24339887640452;11.114916666666653;10.874048913043502;11.175642458100572;11.050552486187854;11.17773743016761;10.756424731182793;11.054033149171264;10.990082417582416;11.050441988950261
08:43:10 INFO - PID 15097 | __end_tp_report
08:43:10 INFO - PID 15097 | __start_cc_report
08:43:10 INFO - PID 15097 | _x_x_mozilla_cycle_collect,611
08:43:10 INFO - PID 15097 | __end_cc_report
08:43:10 INFO - PID 15097 | __startTimestamp1511368990394__endTimestamp
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | ------- Summary: start -------
08:43:10 INFO - PID 15097 | Number of tests: 18
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#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
08:43:10 INFO - PID 15097 | 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
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.2%) stddev-sans-first:0.00
08:43:10 INFO - PID 15097 | 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
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.76 Median:1.76 stddev:0.02 (1.0%) stddev-sans-first:0.02
08:43:10 INFO - PID 15097 | Values: 1.8 1.8 1.7 1.8 1.7 1.8 1.7 1.8 1.8 1.7 1.8 1.8
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.74 Median:1.74 stddev:0.02 (1.3%) stddev-sans-first:0.02
08:43:10 INFO - PID 15097 | Values: 1.7 1.7 1.7 1.8 1.7 1.7 1.7 1.8 1.7 1.7 1.8 1.7
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.09 Median:4.08 stddev:0.10 (2.4%) stddev-sans-first:0.10
08:43:10 INFO - PID 15097 | Values: 4.0 4.1 4.2 4.0 3.9 4.2 4.1 4.0 4.2 4.1 4.2 4.0
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.14 Median:4.19 stddev:0.09 (2.1%) stddev-sans-first:0.09
08:43:10 INFO - PID 15097 | Values: 4.2 4.0 4.2 4.2 4.0 4.2 4.1 4.2 4.2 4.2 4.1 4.2
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.55 Median:3.54 stddev:0.06 (1.8%) stddev-sans-first:0.06
08:43:10 INFO - PID 15097 | Values: 3.5 3.6 3.6 3.5 3.5 3.5 3.5 3.5 3.7 3.5 3.5 3.7
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.47 Median:3.48 stddev:0.04 (1.2%) stddev-sans-first:0.04
08:43:10 INFO - PID 15097 | Values: 3.5 3.5 3.5 3.5 3.4 3.4 3.4 3.5 3.5 3.5 3.4 3.5
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.70 Median:5.69 stddev:0.06 (1.1%) stddev-sans-first:0.07
08:43:10 INFO - PID 15097 | Values: 5.7 5.6 5.7 5.8 5.7 5.8 5.6 5.7 5.7 5.7 5.6 5.8
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.56 Median:5.60 stddev:0.11 (2.0%) stddev-sans-first:0.10
08:43:10 INFO - PID 15097 | Values: 5.4 5.6 5.7 5.4 5.6 5.6 5.5 5.7 5.5 5.7 5.7 5.5
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.91 Median:9.62 stddev:0.29 (3.0%) stddev-sans-first:0.26
08:43:10 INFO - PID 15097 | Values: 10.4 10.1 9.6 9.6 9.7 9.6 9.8 9.6 10.0 9.8 10.2 10.3
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.54 Median:9.59 stddev:0.30 (3.1%) stddev-sans-first:0.29
08:43:10 INFO - PID 15097 | Values: 9.2 9.2 9.7 9.4 10.2 9.7 9.6 9.1 9.6 9.7 9.6 9.5
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.44 Median:11.47 stddev:0.15 (1.3%) stddev-sans-first:0.16
08:43:10 INFO - PID 15097 | Values: 11.5 11.2 11.2 11.5 11.6 11.3 11.5 11.6 11.3 11.5 11.6 11.4
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.94 Median:10.99 stddev:0.13 (1.1%) stddev-sans-first:0.10
08:43:10 INFO - PID 15097 | Values: 10.7 10.9 10.8 10.9 11.0 11.0 11.1 11.1 10.9 11.1 11.1 10.9
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.53 Median:12.58 stddev:0.15 (1.2%) stddev-sans-first:0.16
08:43:10 INFO - PID 15097 | Values: 12.7 12.7 12.3 12.3 12.7 12.5 12.5 12.8 12.4 12.5 12.3 12.7
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.94 Median:11.91 stddev:0.15 (1.3%) stddev-sans-first:0.16
08:43:10 INFO - PID 15097 | Values: 12.1 11.9 11.8 11.9 12.1 11.9 12.2 12.2 11.8 11.8 11.8 11.8
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.57 Median:11.61 stddev:0.20 (1.8%) stddev-sans-first:0.21
08:43:10 INFO - PID 15097 | Values: 11.5 11.5 11.6 11.8 11.2 11.7 11.6 11.9 11.3 11.5 11.4 11.8
08:43:10 INFO - PID 15097 |
08:43:10 INFO - PID 15097 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.02 Median:11.05 stddev:0.16 (1.4%) stddev-sans-first:0.17
08:43:10 INFO - PID 15097 | Values: 11.0 10.8 11.2 11.1 10.9 11.2 11.1 11.2 10.8 11.1 11.0 11.1
08:43:10 INFO - PID 15097 | -------- Summary: end --------
08:43:10 INFO - PID 15097 |
08:43:10 INFO - TEST-INFO | 15097: exit 0
08:43:10 INFO - TEST-OK | basic_compositor_video | took 561296ms
08:43:10 INFO - TEST-START | glvideo
08:43:10 INFO - Initialising browser for glvideo test...
08:43:10 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
08:43:10 INFO - Installing Add-ons
08:43:10 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59351/getInfo.html -profile /tmp/tmpxjPr1Y/profile
08:43:10 INFO - TEST-INFO | started process 16355 (/builds/slave/test/build/application/firefox/firefox http://localhost:59351/getInfo.html)
08:43:16 INFO - TEST-INFO | 16355: exit 0
08:43:16 INFO - Browser initialized.
08:43:16 INFO - Running cycle 1/1 for glvideo test...
08:43:16 INFO - Using env: {'DISPLAY': ':0',
08:43:16 INFO - 'HOME': '/home/cltbld',
08:43:16 INFO - 'JSGC_DISABLE_POISONING': '1',
08:43:16 INFO - 'LANG': 'en_US.UTF-8',
08:43:16 INFO - 'LANGUAGE': 'en_US:en',
08:43:16 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:43:16 INFO - 'LOGNAME': 'cltbld',
08:43:16 INFO - 'MAIL': '/var/mail/cltbld',
08:43:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:43:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:43:16 INFO - 'MOZ_CRASHREPORTER': '1',
08:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:16 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:43:16 INFO - 'MOZ_NO_REMOTE': '1',
08:43:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:43:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:16 INFO - 'NO_EM_RESTART': '1',
08:43:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:16 INFO - 'PWD': '/builds/slave/test',
08:43:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:43:16 INFO - 'RUST_BACKTRACE': 'full',
08:43:16 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:43:16 INFO - 'SHELL': '/bin/bash',
08:43:16 INFO - 'SHLVL': '1',
08:43:16 INFO - 'TERM': 'linux',
08:43:16 INFO - 'TMOUT': '86400',
08:43:16 INFO - 'USER': 'cltbld',
08:43:16 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:16 INFO - '_': '/tools/buildbot/bin/python'}
08:43:16 INFO - TEST-INFO | started process 16629 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxjPr1Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:43:17 INFO - PID 16629 |
08:43:17 INFO - PID 16629 | (/builds/slave/test/build/application/firefox/firefox:16682): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:43:17 INFO - PID 16629 |
08:43:19 INFO - PID 16629 | [talos glvideo result] Mean tick time across 100 ticks: 8.112850000000002 ms
08:43:19 INFO - PID 16629 | Cycle 1(1): loaded http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html)
08:43:20 INFO - PID 16629 | [talos glvideo result] Mean tick time across 100 ticks: 6.630800000000001 ms
08:43:20 INFO - PID 16629 | Cycle 1(2): loaded http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html)
08:43:22 INFO - PID 16629 | [talos glvideo result] Mean tick time across 100 ticks: 6.541949999999999 ms
08:43:22 INFO - PID 16629 | Cycle 1(3): loaded http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html)
08:43:23 INFO - PID 16629 | [talos glvideo result] Mean tick time across 100 ticks: 6.584099999999999 ms
08:43:23 INFO - PID 16629 | Cycle 1(4): loaded http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html)
08:43:24 INFO - PID 16629 | [talos glvideo result] Mean tick time across 100 ticks: 6.5795 ms
08:43:24 INFO - PID 16629 | Cycle 1(5): loaded http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:59351/tests/webgl/benchmarks/video/video_upload.html)
08:43:25 INFO - PID 16629 | __start_tp_report
08:43:25 INFO - PID 16629 | _x_x_mozilla_page_load
08:43:25 INFO - PID 16629 | _x_x_mozilla_page_load_details
08:43:25 INFO - PID 16629 | |i|pagename|runs|
08:43:25 INFO - PID 16629 | |0;Mean tick time across 100 ticks: ;8.112850000000002;6.630800000000001;6.541949999999999;6.584099999999999;6.5795
08:43:25 INFO - PID 16629 | __end_tp_report
08:43:25 INFO - PID 16629 | __start_cc_report
08:43:25 INFO - PID 16629 | _x_x_mozilla_cycle_collect,279
08:43:25 INFO - PID 16629 | __end_cc_report
08:43:25 INFO - PID 16629 | __startTimestamp1511369005115__endTimestamp
08:43:25 INFO - PID 16629 |
08:43:25 INFO - PID 16629 | ------- Summary: start -------
08:43:25 INFO - PID 16629 | Number of tests: 1
08:43:25 INFO - PID 16629 |
08:43:25 INFO - PID 16629 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.89 Median:6.58 stddev:0.68 (10.4%) stddev-sans-first:0.04
08:43:25 INFO - PID 16629 | Values: 8.1 6.6 6.5 6.6 6.6
08:43:25 INFO - PID 16629 | -------- Summary: end --------
08:43:25 INFO - PID 16629 |
08:43:25 INFO - PID 16629 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:43:25 INFO - TEST-INFO | 16629: exit 0
08:43:25 INFO - TEST-OK | glvideo | took 14897ms
08:43:25 INFO - TEST-START | displaylist_mutate
08:43:25 INFO - Initialising browser for displaylist_mutate test...
08:43:25 INFO - Cloning profile located at /builds/slave/test/build/tests/talos/talos/base_profile
08:43:25 INFO - Installing Add-ons
08:43:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59351/getInfo.html -profile /tmp/tmpV0MD4N/profile
08:43:25 INFO - TEST-INFO | started process 16854 (/builds/slave/test/build/application/firefox/firefox http://localhost:59351/getInfo.html)
08:43:31 INFO - TEST-INFO | 16854: exit 0
08:43:31 INFO - Browser initialized.
08:43:31 INFO - Running cycle 1/1 for displaylist_mutate test...
08:43:31 INFO - Using env: {'DISPLAY': ':0',
08:43:31 INFO - 'HOME': '/home/cltbld',
08:43:31 INFO - 'JSGC_DISABLE_POISONING': '1',
08:43:31 INFO - 'LANG': 'en_US.UTF-8',
08:43:31 INFO - 'LANGUAGE': 'en_US:en',
08:43:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:43:31 INFO - 'LOGNAME': 'cltbld',
08:43:31 INFO - 'MAIL': '/var/mail/cltbld',
08:43:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:43:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:43:31 INFO - 'MOZ_CRASHREPORTER': '1',
08:43:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:43:31 INFO - 'MOZ_NO_REMOTE': '1',
08:43:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:43:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:31 INFO - 'NO_EM_RESTART': '1',
08:43:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:31 INFO - 'PWD': '/builds/slave/test',
08:43:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:43:31 INFO - 'RUST_BACKTRACE': 'full',
08:43:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:43:31 INFO - 'SHELL': '/bin/bash',
08:43:31 INFO - 'SHLVL': '1',
08:43:31 INFO - 'TERM': 'linux',
08:43:31 INFO - 'TMOUT': '86400',
08:43:31 INFO - 'USER': 'cltbld',
08:43:31 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406',
08:43:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:31 INFO - '_': '/tools/buildbot/bin/python'}
08:43:31 INFO - TEST-INFO | started process 17120 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV0MD4N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:43:31 INFO - PID 17120 |
08:43:31 INFO - PID 17120 | (/builds/slave/test/build/application/firefox/firefox:17171): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:43:31 INFO - PID 17120 |
08:43:37 INFO - PID 17120 | Cycle 1(1): loaded http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html)
08:43:43 INFO - PID 17120 | Cycle 1(2): loaded http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html)
08:43:49 INFO - PID 17120 | Cycle 1(3): loaded http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html)
08:43:56 INFO - PID 17120 | Cycle 1(4): loaded http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html)
08:44:02 INFO - PID 17120 | Cycle 1(5): loaded http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:59351/tests/layout/benchmarks/displaylist_mutate.html)
08:44:02 INFO - PID 17120 | __start_tp_report
08:44:02 INFO - PID 17120 | _x_x_mozilla_page_load
08:44:02 INFO - PID 17120 | _x_x_mozilla_page_load_details
08:44:02 INFO - PID 17120 | |i|pagename|runs|
08:44:02 INFO - PID 17120 | |0;/displaylist_mutate.html;5058.015;5242.06;5340.120000000001;5421.46;5417.085
08:44:02 INFO - PID 17120 | __end_tp_report
08:44:02 INFO - PID 17120 | __start_cc_report
08:44:02 INFO - PID 17120 | _x_x_mozilla_cycle_collect,256
08:44:02 INFO - PID 17120 | __end_cc_report
08:44:02 INFO - PID 17120 | __startTimestamp1511369042729__endTimestamp
08:44:02 INFO - PID 17120 |
08:44:02 INFO - PID 17120 | ------- Summary: start -------
08:44:02 INFO - PID 17120 | Number of tests: 1
08:44:02 INFO - PID 17120 |
08:44:02 INFO - PID 17120 | [#0] /displaylist_mutate.html Cycles:5 Average:5295.75 Median:5340.12 stddev:151.57 (2.8%) stddev-sans-first:84.16
08:44:02 INFO - PID 17120 | Values: 5058.0 5242.1 5340.1 5421.5 5417.1
08:44:02 INFO - PID 17120 | -------- Summary: end --------
08:44:02 INFO - PID 17120 |
08:44:03 INFO - PID 17120 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:44:03 INFO - TEST-INFO | 17120: exit 0
08:44:03 INFO - TEST-OK | displaylist_mutate | took 37597ms
08:44:03 INFO - SUITE-END | took 613s
08:44:03 INFO - Completed test suite (00:10:14)
08:44:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.602090308921603, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6896537162162162, 1.6827762198541782, 1.6810728291316526, 1.6826724621424567, 1.6819254484304935, 1.6792837157246783, 1.683588664421998, 1.6829136287156479, 1.6819030269058295, 1.688736634777715, 1.6831099270891756, 1.6858258426966293], "value": 1.6827762198541782, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.673047658862876, 1.6785570469798654, 1.6676374999999999, 1.6717460317460313, 1.6724372909698997, 1.6714243943191314, 1.671127819548872, 1.6687989991659715, 1.6672958333333334, 1.6697871452420707, 1.6718045112781952, 1.673761506276151], "value": 1.6714243943191314, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.765129411764706, 1.7775473933649288, 1.7427293844367018, 1.7807566765578633, 1.7463940628637953, 1.7546695906432748, 1.7493702623906706, 1.79431858936043, 1.7609536384976525, 1.7498309037900874, 1.7900536992840097, 1.7580814294083187], "value": 1.7580814294083187, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7471746724890824, 1.7351214223764104, 1.7084756618274979, 1.767672261484099, 1.724650862068965, 1.7430400696864112, 1.7100940170940175, 1.783213012477718, 1.7445771578029647, 1.7413315926892943, 1.757614235500878, 1.7248362068965513], "value": 1.7413315926892943, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.02738255033557, 4.050094466936571, 4.190579608938547, 4.043349056603772, 3.8873186528497414, 4.1732962447844235, 4.093949522510232, 4.043571428571428, 4.179672701949859, 4.065386178861788, 4.244172560113153, 4.033676075268818], "value": 4.065386178861788, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.193679245283019, 4.00815631262525, 4.1763569937369525, 4.2210970464135045, 3.9539525691699584, 4.151037344398342, 4.143167701863353, 4.212842105263158, 4.202899159663866, 4.15036307053942, 4.091584867075665, 4.228847780126852], "value": 4.151037344398342, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4768366164542295, 3.585531660692951, 3.5680856123662332, 3.5304117647058826, 3.5135245901639336, 3.4928870779976733, 3.4765758980301293, 3.533739693757362, 3.677524509803922, 3.5422609208972826, 3.5345524146054172, 3.654549330085262], "value": 3.5345524146054172, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.468214904679376, 3.4915445026178054, 3.496555944055942, 3.48477351916376, 3.443218588640279, 3.4376975945017145, 3.432281303602059, 3.4665164644714044, 3.5471365248226934, 3.4554231433506084, 3.4195555555555597, 3.5476595744680868], "value": 3.4665164644714044, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.683276515151516, 5.61826779026217, 5.671994328922496, 5.803288201160547, 5.727356870229005, 5.803655705996127, 5.6400469924811985, 5.704068441064639, 5.673015122873348, 5.662433962264151, 5.640037593984962, 5.769548076923079], "value": 5.673015122873348, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.36268096514745, 5.555749999999999, 5.683437499999999, 5.363431635388734, 5.586759776536317, 5.603613445378156, 5.51169421487604, 5.666458923512754, 5.540609418282548, 5.6507062146892615, 5.715057142857144, 5.511363636363637], "value": 5.586759776536317, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.384117647058819, 10.137652027027023, 9.646945337620581, 9.617596153846161, 9.74262987012987, 9.585702875399363, 9.772508143322476, 9.616137820512822, 10.003033333333333, 9.839311475409842, 10.241552901023896, 10.275719178082198], "value": 9.772508143322476, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.176330275229352, 9.217004608294932, 9.666086956521726, 9.350046728971963, 10.209132653061214, 9.713373786407757, 9.618028846153843, 9.136529680365287, 9.572942583732052, 9.710485436893189, 9.616298076923071, 9.529261904761906], "value": 9.616298076923071, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.450935114503805, 11.237677902621733, 11.195951492537311, 11.452595419847334, 11.630135658914732, 11.322075471698122, 11.494980842911886, 11.628565891472862, 11.280526315789457, 11.542211538461547, 11.629263565891481, 11.409657794676798], "value": 11.452595419847334, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.699705882352948, 10.871983695652172, 10.754865591397882, 10.930737704918016, 10.99002747252746, 10.991016483516473, 11.050745856353577, 11.05046961325968, 10.870461956521744, 11.050110497237567, 11.114222222222208, 10.930928961748638], "value": 10.99002747252746, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.661350210970456, 12.659324894514777, 12.349567901234566, 12.349506172839508, 12.713728813559301, 12.501791666666668, 12.502145833333332, 12.769765957446825, 12.400371900826444, 12.504395833333335, 12.346028806584359, 12.659092827004196], "value": 12.502145833333332, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.050150602409621, 11.906190476190464, 11.83760355029586, 11.910565476190511, 12.050963855421681, 11.906726190476201, 12.20128048780489, 12.201402439024383, 11.770705882352923, 11.840118343195286, 11.767058823529421, 11.838047337278134], "value": 11.906190476190464, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.45446564885496, 11.539884615384626, 11.583108108108114, 11.767470588235302, 11.237434456928828, 11.71875, 11.631065891472877, 11.905416666666671, 11.322849056603774, 11.495919540229876, 11.408269961977187, 11.814625984251972], "value": 11.583108108108114, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.992939560439542, 10.75438172043012, 11.24339887640452, 11.114916666666653, 10.874048913043502, 11.175642458100572, 11.050552486187854, 11.17773743016761, 10.756424731182793, 11.054033149171264, 10.990082417582416, 11.050441988950261], "value": 11.050552486187854, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8.112850000000002, 6.630800000000001, 6.541949999999999, 6.584099999999999, 6.5795], "value": 6.581799999999999, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5058.015, 5242.06, 5340.120000000001, 5421.46, 5417.085], "value": 5378.602500000001, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
08:44:03 INFO - Return code: 0
08:44:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
08:44:03 INFO - # TBPL SUCCESS #
08:44:03 INFO - Running post-action listener: _package_coverage_data
08:44:03 INFO - Running post-action listener: _resource_record_post_action
08:44:03 INFO - [mozharness: 2017-11-22 16:44:03.943836Z] Finished run-tests step (success)
08:44:03 INFO - Running post-run listener: _resource_record_post_run
08:44:03 INFO - Total resource usage - Wall time: 633s; CPU: 37.0%; Read bytes: 6238208; Write bytes: 337387520; Read time: 476; Write time: 178680
08:44:03 INFO - TinderboxPrint: CPU usage
37.4%
08:44:03 INFO - TinderboxPrint: I/O read bytes / time
6,238,208 / 476
08:44:03 INFO - TinderboxPrint: I/O write bytes / time
337,387,520 / 178,680
08:44:03 INFO - TinderboxPrint: CPU idle
3,111.2 (62.6%)
08:44:03 INFO - TinderboxPrint: CPU system
313.9 (6.3%)
08:44:03 INFO - TinderboxPrint: CPU user
1,527.8 (30.7%)
08:44:03 INFO - TinderboxPrint: Swap in / out
0 / 0
08:44:03 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 5398528; Read time: 0; Write time: 848
08:44:03 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
08:44:04 INFO - run-tests - Wall time: 615s; CPU: 38.0%; Read bytes: 6238208; Write bytes: 330158080; Read time: 476; Write time: 177652
08:44:04 INFO - Running post-run listener: _upload_blobber_files
08:44:04 INFO - Blob upload gear active.
08:44:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
08:44:04 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
08:44:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
08:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
08:44:04 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
08:44:04 INFO - SNIMissingWarning
08:44:04 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
08:44:04 INFO - InsecurePlatformWarning
08:44:04 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.)
08:44:04 INFO - SubjectAltNameWarning
08:44:04 INFO - (blobuploader) - INFO - Open directory for files ...
08:44:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
08:44:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:44:04 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:44:04 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
08:44:04 INFO - InsecurePlatformWarning
08:44:04 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.)
08:44:04 INFO - SubjectAltNameWarning
08:44:05 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
08:44:05 INFO - InsecurePlatformWarning
08:44:05 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
08:44:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:44:05 INFO - (blobuploader) - INFO - Done attempting.
08:44:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
08:44:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:44:05 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:44:05 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
08:44:05 INFO - InsecurePlatformWarning
08:44:05 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.)
08:44:05 INFO - SubjectAltNameWarning
08:44:07 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
08:44:07 INFO - InsecurePlatformWarning
08:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:44:08 INFO - (blobuploader) - INFO - Done attempting.
08:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
08:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:44:08 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
08:44:08 INFO - InsecurePlatformWarning
08:44:08 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.)
08:44:08 INFO - SubjectAltNameWarning
08:44:08 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
08:44:08 INFO - InsecurePlatformWarning
08:44:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
08:44:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:44:08 INFO - (blobuploader) - INFO - Done attempting.
08:44:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
08:44:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:44:08 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:44:09 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
08:44:09 INFO - InsecurePlatformWarning
08:44:09 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.)
08:44:09 INFO - SubjectAltNameWarning
08:44:09 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
08:44:09 INFO - InsecurePlatformWarning
08:44:09 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
08:44:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:44:09 INFO - (blobuploader) - INFO - Done attempting.
08:44:09 INFO - (blobuploader) - INFO - Iteration through files over.
08:44:09 INFO - Return code: 0
08:44:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
08:44:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
08:44:09 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/483acbcaa264e84681bded41a14431528beda4f93f43a62765ca82ed7533ff18d3a8629ea5c4f77b10b7b731e9f3d9f4244b6e68bb96b8e27286e56ed3f51162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3af94ae6449369a013e8ccec3996efae261118c4ef4176ee94b6aa1f2c6ce2a4a65f1401b0d3ca275925084a85422c38d365d17f22a1d188e17bc3a7adc180a", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e6f7c7fc6a6ccb5bfabfc4ab49ce59e726b4ad10f828959216fa5654dad1439c5eee665cd606af23f1b661f6498868c7d88285949b1ad990ceff7d64a05114f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57e928a66b023658409e7ddbd41a232ec3261d99b57f6f17f14e47519eda8f1937b9323e43717a7c8eb69e45b8a12c66129e51bc6cfb2ced3baff1ec965907ed"}
08:44:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
08:44:09 INFO - Writing to file /builds/slave/test/properties/blobber_files
08:44:09 INFO - Contents:
08:44:09 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/483acbcaa264e84681bded41a14431528beda4f93f43a62765ca82ed7533ff18d3a8629ea5c4f77b10b7b731e9f3d9f4244b6e68bb96b8e27286e56ed3f51162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3af94ae6449369a013e8ccec3996efae261118c4ef4176ee94b6aa1f2c6ce2a4a65f1401b0d3ca275925084a85422c38d365d17f22a1d188e17bc3a7adc180a", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e6f7c7fc6a6ccb5bfabfc4ab49ce59e726b4ad10f828959216fa5654dad1439c5eee665cd606af23f1b661f6498868c7d88285949b1ad990ceff7d64a05114f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57e928a66b023658409e7ddbd41a232ec3261d99b57f6f17f14e47519eda8f1937b9323e43717a7c8eb69e45b8a12c66129e51bc6cfb2ced3baff1ec965907ed"}
08:44:09 INFO - Running post-run listener: copy_logs_to_upload_dir
08:44:09 INFO - Copying logs to upload dir...
08:44:09 INFO - mkdir: /builds/slave/test/build/upload/logs
08:44:09 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=692.970419
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 33 secs) (at 2017-11-22 08:44:09.855595) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-22 08:44:09.860044) =========
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=41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/483acbcaa264e84681bded41a14431528beda4f93f43a62765ca82ed7533ff18d3a8629ea5c4f77b10b7b731e9f3d9f4244b6e68bb96b8e27286e56ed3f51162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3af94ae6449369a013e8ccec3996efae261118c4ef4176ee94b6aa1f2c6ce2a4a65f1401b0d3ca275925084a85422c38d365d17f22a1d188e17bc3a7adc180a", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e6f7c7fc6a6ccb5bfabfc4ab49ce59e726b4ad10f828959216fa5654dad1439c5eee665cd606af23f1b661f6498868c7d88285949b1ad990ceff7d64a05114f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57e928a66b023658409e7ddbd41a232ec3261d99b57f6f17f14e47519eda8f1937b9323e43717a7c8eb69e45b8a12c66129e51bc6cfb2ced3baff1ec965907ed"}
build_url:https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011365
build_url: 'https://queue.taskcluster.net/v1/task/G3kTrmh9Q1aM9DgrXEzRdg/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/483acbcaa264e84681bded41a14431528beda4f93f43a62765ca82ed7533ff18d3a8629ea5c4f77b10b7b731e9f3d9f4244b6e68bb96b8e27286e56ed3f51162", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/d3af94ae6449369a013e8ccec3996efae261118c4ef4176ee94b6aa1f2c6ce2a4a65f1401b0d3ca275925084a85422c38d365d17f22a1d188e17bc3a7adc180a", "g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/6e6f7c7fc6a6ccb5bfabfc4ab49ce59e726b4ad10f828959216fa5654dad1439c5eee665cd606af23f1b661f6498868c7d88285949b1ad990ceff7d64a05114f", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/57e928a66b023658409e7ddbd41a232ec3261d99b57f6f17f14e47519eda8f1937b9323e43717a7c8eb69e45b8a12c66129e51bc6cfb2ced3baff1ec965907ed"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-22 08:44:09.889614) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 08:44:09.889934) =========
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=41a7e32711b8e08598a63237000001d6-1511364653.101719-2080462406
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004580
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-22 08:44:09.974937) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-22 08:44:09.975236) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-22 08:44:09.975519) =========
========= Total master_lag: 0.20 =========