builder: mozilla-beta_ubuntu64_hw_test-g4-e10s
slave: talos-linux64-ix-011
starttime: 1509723761.19
results: success (0)
revision: e65a20ee3a063aa94ea61059b80dce6bc065fa91
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.192135) =========
master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.198670) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.198955) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.254646) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.254915) =========
bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-03 08:42:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 226M=0s
2017-11-03 08:42:41 (226 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.199685
========= master_lag: 0.06 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.513685) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.513960) =========
rm -rf scripts properties
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-rf', 'scripts', 'properties']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020626
========= master_lag: 0.07 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:41.604915) =========
========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-03 08:42:41.605215) =========
bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e65a20ee3a063aa94ea61059b80dce6bc065fa91 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev e65a20ee3a063aa94ea61059b80dce6bc065fa91 --destination scripts --debug']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-03 08:42:41,695 truncating revision to first 12 chars
2017-11-03 08:42:41,695 Setting DEBUG logging.
2017-11-03 08:42:41,695 attempt 1/10
2017-11-03 08:42:41,695 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/e65a20ee3a06?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-03 08:42:42,266 unpacking tar archive at: mozilla-beta-e65a20ee3a06/testing/mozharness/
program finished with exit code 0
elapsedTime=1.172627
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-11-03 08:42:42.805963) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:42.809254) =========
script_repo_revision: e65a20ee3a063aa94ea61059b80dce6bc065fa91
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:42.809597) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:42.809851) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-03 08:42:42.826249) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 57 secs) (at 2017-11-03 08:42:42.826506) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
08:42:43 INFO - MultiFileLogger online at 20171103 08:42:43 in /builds/slave/test
08:42:43 INFO - Run as scripts/scripts/talos_script.py --suite g4-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta-Non-PGO
08:42:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
08:42:43 INFO - {'append_to_log': False,
08:42:43 INFO - 'base_work_dir': '/builds/slave/test',
08:42:43 INFO - 'blob_upload_branch': 'Mozilla-Beta-Non-PGO',
08:42:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
08:42:43 INFO - 'branch': 'Mozilla-Beta-Non-PGO',
08:42:43 INFO - 'buildbot_json_path': 'buildprops.json',
08:42:43 INFO - 'code_coverage': False,
08:42:43 INFO - 'config_files': ('talos/linux_config.py',),
08:42:43 INFO - 'default_actions': ('clobber',
08:42:43 INFO - 'read-buildbot-config',
08:42:43 INFO - 'download-and-extract',
08:42:43 INFO - 'populate-webroot',
08:42:43 INFO - 'create-virtualenv',
08:42:43 INFO - 'install',
08:42:43 INFO - 'setup-mitmproxy',
08:42:43 INFO - 'run-tests'),
08:42:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
08:42:43 INFO - 'disable_ccov_upload': False,
08:42:43 INFO - 'disable_stylo': False,
08:42:43 INFO - 'download_minidump_stackwalk': True,
08:42:43 INFO - 'download_symbols': 'ondemand',
08:42:43 INFO - 'e10s': False,
08:42:43 INFO - 'enable_stylo': False,
08:42:43 INFO - 'enable_webrender': False,
08:42:43 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
08:42:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
08:42:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
08:42:43 INFO - 'gecko_profile': False,
08:42:43 INFO - 'gecko_profile_interval': 0,
08:42:43 INFO - 'installer_path': 'installer.exe',
08:42:43 INFO - 'jsd_code_coverage': False,
08:42:43 INFO - 'log_level': 'info',
08:42:43 INFO - 'log_name': 'talos',
08:42:43 INFO - 'log_to_console': True,
08:42:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
08:42:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
08:42:43 INFO - 'opt_config_files': (),
08:42:43 INFO - 'pip_index': False,
08:42:43 INFO - 'suite': 'g4-e10s',
08:42:43 INFO - 'system_bits': '32',
08:42:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
08:42:43 INFO - 'title': 'talos-linux64-ix-011',
08:42:43 INFO - 'tooltool_cache': '/builds/tooltool_cache',
08:42:43 INFO - 'use_talos_json': True,
08:42:43 INFO - 'verify': 'False',
08:42:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
08:42:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
08:42:43 INFO - 'work_dir': 'build'}
08:42:43 INFO - [mozharness: 2017-11-03 15:42:43.148876Z] Running clobber step.
08:42:43 INFO - Running pre-action listener: _resource_record_pre_action
08:42:43 INFO - Running main action method: clobber
08:42:43 INFO - rmtree: /builds/slave/test/build
08:42:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
08:42:44 INFO - Running post-action listener: _resource_record_post_action
08:42:44 INFO - [mozharness: 2017-11-03 15:42:44.158144Z] Finished clobber step (success)
08:42:44 INFO - [mozharness: 2017-11-03 15:42:44.158240Z] Running read-buildbot-config step.
08:42:44 INFO - Running pre-action listener: _resource_record_pre_action
08:42:44 INFO - Running main action method: read_buildbot_config
08:42:44 INFO - Using buildbot properties:
08:42:44 INFO - {
08:42:44 INFO - "project": "",
08:42:44 INFO - "product": "firefox",
08:42:44 INFO - "who": "jlorenzo@mozilla.com",
08:42:44 INFO - "installer_path": "public/build/target.tar.bz2",
08:42:44 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-g4-e10s",
08:42:44 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta",
08:42:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta talos g4-e10s",
08:42:44 INFO - "stage_platform": "linux64",
08:42:44 INFO - "basedir": "/builds/slave/test",
08:42:44 INFO - "buildnumber": 251,
08:42:44 INFO - "platform": "ubuntu64_hw",
08:42:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/",
08:42:44 INFO - "slavebuilddir": "test",
08:42:44 INFO - "taskId": "HOKt-NCkTWCfyPEgbEv7mA",
08:42:44 INFO - "branch": "mozilla-beta",
08:42:44 INFO - "script_repo_revision": "production",
08:42:44 INFO - "revision": "e65a20ee3a063aa94ea61059b80dce6bc065fa91",
08:42:44 INFO - "slavename": "talos-linux64-ix-011",
08:42:44 INFO - "repo_path": "releases/mozilla-beta"
08:42:44 INFO - }
08:42:44 INFO - Finding installer, test and symbols from parent task.
08:42:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HOKt-NCkTWCfyPEgbEv7mA'}, attempt #1
08:42:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HOKt-NCkTWCfyPEgbEv7mA'}, attempt #1
08:42:45 INFO - Task dependencies: ejFas9P2QW2HGeFRR0OdGg
08:42:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg'}, attempt #1
08:42:45 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2
08:42:45 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.test_packages.json
08:42:45 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.crashreporter-symbols.zip
08:42:45 INFO - Running post-action listener: _resource_record_post_action
08:42:45 INFO - [mozharness: 2017-11-03 15:42:45.595436Z] Finished read-buildbot-config step (success)
08:42:45 INFO - [mozharness: 2017-11-03 15:42:45.595657Z] Running download-and-extract step.
08:42:45 INFO - Running pre-action listener: _resource_record_pre_action
08:42:45 INFO - Running main action method: download_and_extract
08:42:45 INFO - mkdir: /builds/slave/test/build/tests
08:42:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:42:45 INFO - trying https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.test_packages.json
08:42:45 INFO - Downloading https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
08:42:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
08:42:46 INFO - Downloaded 989 bytes.
08:42:46 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
08:42:46 INFO - Using the following test package requirements:
08:42:46 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
08:42:46 INFO - u'common': [u'target.common.tests.zip'],
08:42:46 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
08:42:46 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
08:42:46 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
08:42:46 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
08:42:46 INFO - u'mozbase': [u'target.common.tests.zip'],
08:42:46 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
08:42:46 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
08:42:46 INFO - u'web-platform': [u'target.common.tests.zip',
08:42:46 INFO - u'target.web-platform.tests.tar.gz'],
08:42:46 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
08:42:46 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
08:42:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.common.tests.zip
08:42:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.common.tests.zip'}, attempt #1
08:42:46 INFO - Fetch https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.common.tests.zip into memory
08:42:47 INFO - Content-Length response header: 56672580
08:42:47 INFO - Bytes received: 56672580
08:42:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.talos.tests.zip
08:42:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.talos.tests.zip'}, attempt #1
08:42:58 INFO - Fetch https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.talos.tests.zip into memory
08:42:59 INFO - Content-Length response header: 18771919
08:42:59 INFO - Bytes received: 18771919
08:43:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:00 INFO - trying https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2
08:43:00 INFO - Downloading https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
08:43:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
08:43:01 INFO - Downloaded 54340912 bytes.
08:43:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2
08:43:01 INFO - mkdir: /builds/slave/test/properties
08:43:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
08:43:01 INFO - Writing to file /builds/slave/test/properties/build_url
08:43:01 INFO - Contents:
08:43:01 INFO - build_url:https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2
08:43:01 INFO - Running post-action listener: _resource_record_post_action
08:43:01 INFO - Running post-action listener: find_tests_for_verification
08:43:01 INFO - Running post-action listener: set_extra_try_arguments
08:43:01 INFO - [mozharness: 2017-11-03 15:43:01.341500Z] Finished download-and-extract step (success)
08:43:01 INFO - [mozharness: 2017-11-03 15:43:01.341594Z] Running populate-webroot step.
08:43:01 INFO - Running pre-action listener: _resource_record_pre_action
08:43:01 INFO - Running main action method: populate_webroot
08:43:01 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
08:43:01 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['tresize',
08:43:01 INFO - 'tcanvasmark']},
08:43:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
08:43:01 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['dromaeo_css',
08:43:01 INFO - 'kraken']},
08:43:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:43:01 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:43:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'tests': ['damp', 'tps']},
08:43:01 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['damp', 'tps']},
08:43:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
08:43:01 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['dromaeo_dom']},
08:43:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
08:43:01 INFO - 'glvideo',
08:43:01 INFO - 'displaylist_mutate']},
08:43:01 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['basic_compositor_video',
08:43:01 INFO - 'glvideo']},
08:43:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
08:43:01 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['ts_paint_webext',
08:43:01 INFO - 'tp5o_webext']},
08:43:01 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
08:43:01 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:43:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:43:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:43:01 INFO - 'talos_options': ['--mitmproxy',
08:43:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:43:01 INFO - '--firstNonBlankPaint'],
08:43:01 INFO - 'tests': ['tp6_google_heavy',
08:43:01 INFO - 'tp6_youtube_heavy',
08:43:01 INFO - 'tp6_amazon_heavy',
08:43:01 INFO - 'tp6_facebook_heavy']},
08:43:01 INFO - 'other-e10s': {'tests': ['a11yr',
08:43:01 INFO - 'ts_paint',
08:43:01 INFO - 'tpaint',
08:43:01 INFO - 'sessionrestore',
08:43:01 INFO - 'sessionrestore_many_windows',
08:43:01 INFO - 'sessionrestore_no_auto_restore',
08:43:01 INFO - 'tabpaint',
08:43:01 INFO - 'cpstartup']},
08:43:01 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['a11yr',
08:43:01 INFO - 'ts_paint',
08:43:01 INFO - 'tpaint',
08:43:01 INFO - 'sessionrestore',
08:43:01 INFO - 'sessionrestore_many_windows',
08:43:01 INFO - 'sessionrestore_no_auto_restore',
08:43:01 INFO - 'tabpaint',
08:43:01 INFO - 'cpstartup']},
08:43:01 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
08:43:01 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
08:43:01 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['perf_reftest_singletons']},
08:43:01 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['perf_reftest']},
08:43:01 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
08:43:01 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['speedometer']},
08:43:01 INFO - 'svgr-e10s': {'tests': ['tsvgx',
08:43:01 INFO - 'tsvgr_opacity',
08:43:01 INFO - 'tart',
08:43:01 INFO - 'tscrollx',
08:43:01 INFO - 'tsvg_static']},
08:43:01 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['tsvgx',
08:43:01 INFO - 'tsvgr_opacity',
08:43:01 INFO - 'tart',
08:43:01 INFO - 'tscrollx',
08:43:01 INFO - 'tsvg_static']},
08:43:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
08:43:01 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'talos_options': ['--disable-stylo'],
08:43:01 INFO - 'tests': ['tp5o']},
08:43:01 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:43:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:43:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:43:01 INFO - 'talos_options': ['--mitmproxy',
08:43:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:43:01 INFO - '--firstNonBlankPaint'],
08:43:01 INFO - 'tests': ['tp6_google',
08:43:01 INFO - 'tp6_youtube',
08:43:01 INFO - 'tp6_amazon',
08:43:01 INFO - 'tp6_facebook']},
08:43:01 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:43:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:43:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:43:01 INFO - 'talos_options': ['--disable-stylo',
08:43:01 INFO - '--mitmproxy',
08:43:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:43:01 INFO - '--firstNonBlankPaint'],
08:43:01 INFO - 'tests': ['tp6_google',
08:43:01 INFO - 'tp6_youtube',
08:43:01 INFO - 'tp6_amazon',
08:43:01 INFO - 'tp6_facebook']},
08:43:01 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:43:01 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:43:01 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:43:01 INFO - 'talos_options': ['--stylo-threads=1',
08:43:01 INFO - '--mitmproxy',
08:43:01 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:43:01 INFO - '--firstNonBlankPaint'],
08:43:01 INFO - 'tests': ['tp6_google',
08:43:01 INFO - 'tp6_youtube',
08:43:01 INFO - 'tp6_amazon',
08:43:01 INFO - 'tp6_facebook']},
08:43:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'talos_options': ['--xperf_path',
08:43:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:43:01 INFO - 'tests': ['tp5n']},
08:43:01 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:43:01 INFO - 'talos_options': ['--disable-stylo',
08:43:01 INFO - '--xperf_path',
08:43:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:43:01 INFO - 'tests': ['tp5n']}}}
08:43:01 INFO - Running post-action listener: _resource_record_post_action
08:43:01 INFO - [mozharness: 2017-11-03 15:43:01.360300Z] Finished populate-webroot step (success)
08:43:01 INFO - [mozharness: 2017-11-03 15:43:01.360394Z] Running create-virtualenv step.
08:43:01 INFO - Running pre-action listener: _resource_record_pre_action
08:43:01 INFO - Running main action method: create_virtualenv
08:43:01 INFO - Creating virtualenv /builds/slave/test/build/venv
08:43:01 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
08:43:01 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
08:43:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
08:43:05 INFO - Using real prefix '/usr'
08:43:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python
08:43:05 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
08:43:08 INFO - Installing setuptools, pip, wheel...done.
08:43:08 INFO - Return code: 0
08:43:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
08:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
08:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
08:43:08 INFO - Using env: {'DISPLAY': ':0',
08:43:08 INFO - 'HOME': '/home/cltbld',
08:43:08 INFO - 'LANG': 'en_US.UTF-8',
08:43:08 INFO - 'LANGUAGE': 'en_US:en',
08:43:08 INFO - 'LOGNAME': 'cltbld',
08:43:08 INFO - 'MAIL': '/var/mail/cltbld',
08:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:08 INFO - 'MOZ_NO_REMOTE': '1',
08:43:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:08 INFO - 'NO_EM_RESTART': '1',
08:43:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:08 INFO - 'PWD': '/builds/slave/test',
08:43:08 INFO - 'SHELL': '/bin/bash',
08:43:08 INFO - 'SHLVL': '1',
08:43:08 INFO - 'TERM': 'linux',
08:43:08 INFO - 'TMOUT': '86400',
08:43:08 INFO - 'USER': 'cltbld',
08:43:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:08 INFO - '_': '/tools/buildbot/bin/python'}
08:43:08 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
08:43:08 INFO - Return code: 0
08:43:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
08:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
08:43:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
08:43:08 INFO - Using env: {'DISPLAY': ':0',
08:43:08 INFO - 'HOME': '/home/cltbld',
08:43:08 INFO - 'LANG': 'en_US.UTF-8',
08:43:08 INFO - 'LANGUAGE': 'en_US:en',
08:43:08 INFO - 'LOGNAME': 'cltbld',
08:43:08 INFO - 'MAIL': '/var/mail/cltbld',
08:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:08 INFO - 'MOZ_NO_REMOTE': '1',
08:43:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:08 INFO - 'NO_EM_RESTART': '1',
08:43:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:08 INFO - 'PWD': '/builds/slave/test',
08:43:08 INFO - 'SHELL': '/bin/bash',
08:43:08 INFO - 'SHLVL': '1',
08:43:08 INFO - 'TERM': 'linux',
08:43:08 INFO - 'TMOUT': '86400',
08:43:08 INFO - 'USER': 'cltbld',
08:43:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:08 INFO - '_': '/tools/buildbot/bin/python'}
08:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:09 INFO - Collecting psutil>=3.1.1
08:43:10 INFO - Installing collected packages: psutil
08:43:10 INFO - Successfully installed psutil-3.1.1
08:43:10 INFO - Return code: 0
08:43:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
08:43:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:10 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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:10 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:43:10 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:43:10 INFO - Using env: {'DISPLAY': ':0',
08:43:10 INFO - 'HOME': '/home/cltbld',
08:43:10 INFO - 'LANG': 'en_US.UTF-8',
08:43:10 INFO - 'LANGUAGE': 'en_US:en',
08:43:10 INFO - 'LOGNAME': 'cltbld',
08:43:10 INFO - 'MAIL': '/var/mail/cltbld',
08:43:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:10 INFO - 'MOZ_NO_REMOTE': '1',
08:43:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:10 INFO - 'NO_EM_RESTART': '1',
08:43:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:10 INFO - 'PWD': '/builds/slave/test',
08:43:10 INFO - 'SHELL': '/bin/bash',
08:43:10 INFO - 'SHLVL': '1',
08:43:10 INFO - 'TERM': 'linux',
08:43:10 INFO - 'TMOUT': '86400',
08:43:10 INFO - 'USER': 'cltbld',
08:43:10 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:10 INFO - '_': '/tools/buildbot/bin/python'}
08:43:10 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:10 INFO - Collecting mozsystemmonitor==0.3
08:43:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
08:43:11 INFO - Installing collected packages: mozsystemmonitor
08:43:12 INFO - Successfully installed mozsystemmonitor-0.3
08:43:12 INFO - Return code: 0
08:43:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
08:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
08:43:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
08:43:12 INFO - Using env: {'DISPLAY': ':0',
08:43:12 INFO - 'HOME': '/home/cltbld',
08:43:12 INFO - 'LANG': 'en_US.UTF-8',
08:43:12 INFO - 'LANGUAGE': 'en_US:en',
08:43:12 INFO - 'LOGNAME': 'cltbld',
08:43:12 INFO - 'MAIL': '/var/mail/cltbld',
08:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:12 INFO - 'MOZ_NO_REMOTE': '1',
08:43:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:12 INFO - 'NO_EM_RESTART': '1',
08:43:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:12 INFO - 'PWD': '/builds/slave/test',
08:43:12 INFO - 'SHELL': '/bin/bash',
08:43:12 INFO - 'SHLVL': '1',
08:43:12 INFO - 'TERM': 'linux',
08:43:12 INFO - 'TMOUT': '86400',
08:43:12 INFO - 'USER': 'cltbld',
08:43:12 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:12 INFO - '_': '/tools/buildbot/bin/python'}
08:43:12 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:12 INFO - Collecting jsonschema==2.5.1
08:43:13 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
08:43:13 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
08:43:14 INFO - Installing collected packages: functools32, jsonschema
08:43:14 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
08:43:14 INFO - Return code: 0
08:43:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
08:43:14 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:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:14 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:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:14 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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:14 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:43:14 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:43:14 INFO - Using env: {'DISPLAY': ':0',
08:43:14 INFO - 'HOME': '/home/cltbld',
08:43:14 INFO - 'LANG': 'en_US.UTF-8',
08:43:14 INFO - 'LANGUAGE': 'en_US:en',
08:43:14 INFO - 'LOGNAME': 'cltbld',
08:43:14 INFO - 'MAIL': '/var/mail/cltbld',
08:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:14 INFO - 'MOZ_NO_REMOTE': '1',
08:43:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:14 INFO - 'NO_EM_RESTART': '1',
08:43:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:14 INFO - 'PWD': '/builds/slave/test',
08:43:14 INFO - 'SHELL': '/bin/bash',
08:43:14 INFO - 'SHLVL': '1',
08:43:14 INFO - 'TERM': 'linux',
08:43:14 INFO - 'TMOUT': '86400',
08:43:14 INFO - 'USER': 'cltbld',
08:43:14 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:14 INFO - '_': '/tools/buildbot/bin/python'}
08:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
08:43:15 INFO - Return code: 0
08:43:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
08:43: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:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43: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:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:15 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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:15 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:43:15 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:43:15 INFO - Using env: {'DISPLAY': ':0',
08:43:15 INFO - 'HOME': '/home/cltbld',
08:43:15 INFO - 'LANG': 'en_US.UTF-8',
08:43:15 INFO - 'LANGUAGE': 'en_US:en',
08:43:15 INFO - 'LOGNAME': 'cltbld',
08:43:15 INFO - 'MAIL': '/var/mail/cltbld',
08:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:15 INFO - 'MOZ_NO_REMOTE': '1',
08:43:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:15 INFO - 'NO_EM_RESTART': '1',
08:43:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:15 INFO - 'PWD': '/builds/slave/test',
08:43:15 INFO - 'SHELL': '/bin/bash',
08:43:15 INFO - 'SHLVL': '1',
08:43:15 INFO - 'TERM': 'linux',
08:43:15 INFO - 'TMOUT': '86400',
08:43:15 INFO - 'USER': 'cltbld',
08:43:15 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:15 INFO - '_': '/tools/buildbot/bin/python'}
08:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:15 INFO - Collecting blobuploader==1.2.4
08:43:16 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
08:43:17 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
08:43:18 INFO - Installing collected packages: requests, docopt, blobuploader
08:43:18 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
08:43:18 INFO - Return code: 0
08:43:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:43:18 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:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:18 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:43:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:18 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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:18 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:43:18 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:43:18 INFO - Using env: {'DISPLAY': ':0',
08:43:18 INFO - 'HOME': '/home/cltbld',
08:43:18 INFO - 'LANG': 'en_US.UTF-8',
08:43:18 INFO - 'LANGUAGE': 'en_US:en',
08:43:18 INFO - 'LOGNAME': 'cltbld',
08:43:18 INFO - 'MAIL': '/var/mail/cltbld',
08:43:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:18 INFO - 'MOZ_NO_REMOTE': '1',
08:43:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:18 INFO - 'NO_EM_RESTART': '1',
08:43:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:18 INFO - 'PWD': '/builds/slave/test',
08:43:18 INFO - 'SHELL': '/bin/bash',
08:43:18 INFO - 'SHLVL': '1',
08:43:18 INFO - 'TERM': 'linux',
08:43:18 INFO - 'TMOUT': '86400',
08:43:18 INFO - 'USER': 'cltbld',
08:43:18 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:18 INFO - '_': '/tools/buildbot/bin/python'}
08:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:19 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:43:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:43:19 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:43:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:43:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:43:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:43:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:43:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:43:20 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:43:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:43:21 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:43:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:43:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:43:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:43:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:43:22 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:43:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:43:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
08:43:22 INFO - Running setup.py install for manifestparser: started
08:43:22 INFO - Running setup.py install for manifestparser: finished with status 'done'
08:43:22 INFO - Running setup.py install for mozcrash: started
08:43:23 INFO - Running setup.py install for mozcrash: finished with status 'done'
08:43:23 INFO - Running setup.py install for mozdebug: started
08:43:23 INFO - Running setup.py install for mozdebug: finished with status 'done'
08:43:23 INFO - Running setup.py install for mozdevice: started
08:43:23 INFO - Running setup.py install for mozdevice: finished with status 'done'
08:43:23 INFO - Running setup.py install for mozfile: started
08:43:23 INFO - Running setup.py install for mozfile: finished with status 'done'
08:43:23 INFO - Running setup.py install for mozhttpd: started
08:43:23 INFO - Running setup.py install for mozhttpd: finished with status 'done'
08:43:23 INFO - Running setup.py install for mozinfo: started
08:43:24 INFO - Running setup.py install for mozinfo: finished with status 'done'
08:43:24 INFO - Running setup.py install for mozInstall: started
08:43:24 INFO - Running setup.py install for mozInstall: finished with status 'done'
08:43:24 INFO - Running setup.py install for mozleak: started
08:43:24 INFO - Running setup.py install for mozleak: finished with status 'done'
08:43:24 INFO - Running setup.py install for mozlog: started
08:43:24 INFO - Running setup.py install for mozlog: finished with status 'done'
08:43:24 INFO - Running setup.py install for moznetwork: started
08:43:25 INFO - Running setup.py install for moznetwork: finished with status 'done'
08:43:25 INFO - Running setup.py install for mozprocess: started
08:43:25 INFO - Running setup.py install for mozprocess: finished with status 'done'
08:43:25 INFO - Running setup.py install for mozprofile: started
08:43:25 INFO - Running setup.py install for mozprofile: finished with status 'done'
08:43:25 INFO - Running setup.py install for mozrunner: started
08:43:25 INFO - Running setup.py install for mozrunner: finished with status 'done'
08:43:25 INFO - Running setup.py install for mozscreenshot: started
08:43:25 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
08:43:26 INFO - Running setup.py install for moztest: started
08:43:26 INFO - Running setup.py install for moztest: finished with status 'done'
08:43:26 INFO - Running setup.py install for mozversion: started
08:43:26 INFO - Running setup.py install for mozversion: finished with status 'done'
08:43:26 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:43:26 INFO - Return code: 0
08:43:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:26 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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:26 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:43:26 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:43:26 INFO - Using env: {'DISPLAY': ':0',
08:43:26 INFO - 'HOME': '/home/cltbld',
08:43:26 INFO - 'LANG': 'en_US.UTF-8',
08:43:26 INFO - 'LANGUAGE': 'en_US:en',
08:43:26 INFO - 'LOGNAME': 'cltbld',
08:43:26 INFO - 'MAIL': '/var/mail/cltbld',
08:43:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:26 INFO - 'MOZ_NO_REMOTE': '1',
08:43:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:26 INFO - 'NO_EM_RESTART': '1',
08:43:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:26 INFO - 'PWD': '/builds/slave/test',
08:43:26 INFO - 'SHELL': '/bin/bash',
08:43:26 INFO - 'SHLVL': '1',
08:43:26 INFO - 'TERM': 'linux',
08:43:26 INFO - 'TMOUT': '86400',
08:43:26 INFO - 'USER': 'cltbld',
08:43:26 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:26 INFO - '_': '/tools/buildbot/bin/python'}
08:43:27 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:43:27 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:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:43:27 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:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:43:27 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:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:43:28 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:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:43:28 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:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:43:28 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:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:43:28 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:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:43:28 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:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:43:29 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:43:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:43:29 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:43:29 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:43:29 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:43:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:43:29 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:43:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:43:29 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:43:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:43:30 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:43:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:43:30 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:43:30 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:43:30 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:43:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:43:30 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:43:30 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
08:43:31 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
08:43:31 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:43:31 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:43:31 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:43:31 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:43:31 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:43:31 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:43:31 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
08:43:32 INFO - Installing collected packages: six, blessings
08:43:32 INFO - Successfully installed blessings-1.6 six-1.10.0
08:43:32 INFO - Return code: 0
08:43:32 INFO - Done creating virtualenv /builds/slave/test/build/venv.
08:43:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:43:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:43:33 INFO - Reading from file tmpfile_stdout
08:43:33 INFO - Reading from file tmpfile_stderr
08:43:33 INFO - Current package versions:
08:43:33 INFO - blessings == 1.6
08:43:33 INFO - blobuploader == 1.2.4
08:43:33 INFO - docopt == 0.6.1
08:43:33 INFO - functools32 == 3.2.3.post2
08:43:33 INFO - jsonschema == 2.5.1
08:43:33 INFO - manifestparser == 1.2
08:43:33 INFO - mozInstall == 1.14
08:43:33 INFO - mozcrash == 1.0
08:43:33 INFO - mozdebug == 0.1
08:43:33 INFO - mozdevice == 0.51
08:43:33 INFO - mozfile == 1.2
08:43:33 INFO - mozhttpd == 0.7
08:43:33 INFO - mozinfo == 0.10
08:43:33 INFO - mozleak == 0.1
08:43:33 INFO - mozlog == 3.5
08:43:33 INFO - moznetwork == 0.27
08:43:33 INFO - mozprocess == 0.25
08:43:33 INFO - mozprofile == 0.29
08:43:33 INFO - mozrunner == 6.13
08:43:33 INFO - mozscreenshot == 0.1
08:43:33 INFO - mozsystemmonitor == 0.3
08:43:33 INFO - moztest == 0.8
08:43:33 INFO - mozversion == 1.4
08:43:33 INFO - psutil == 3.1.1
08:43:33 INFO - requests == 1.2.3
08:43:33 INFO - six == 1.10.0
08:43:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:43:33 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:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:33 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:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:33 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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:33 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:43:33 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:43:33 INFO - Using env: {'DISPLAY': ':0',
08:43:33 INFO - 'HOME': '/home/cltbld',
08:43:33 INFO - 'LANG': 'en_US.UTF-8',
08:43:33 INFO - 'LANGUAGE': 'en_US:en',
08:43:33 INFO - 'LOGNAME': 'cltbld',
08:43:33 INFO - 'MAIL': '/var/mail/cltbld',
08:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:33 INFO - 'MOZ_NO_REMOTE': '1',
08:43:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:33 INFO - 'NO_EM_RESTART': '1',
08:43:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:33 INFO - 'PWD': '/builds/slave/test',
08:43:33 INFO - 'SHELL': '/bin/bash',
08:43:33 INFO - 'SHLVL': '1',
08:43:33 INFO - 'TERM': 'linux',
08:43:33 INFO - 'TMOUT': '86400',
08:43:33 INFO - 'USER': 'cltbld',
08:43:33 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:33 INFO - '_': '/tools/buildbot/bin/python'}
08:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:33 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:43:33 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:43:33 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:43:33 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:43:33 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:43:33 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:43:33 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:43:33 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:43:33 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:43:33 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
08:43:35 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:43:36 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
08:43:36 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:43:36 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:43:36 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:43:36 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:43:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
08:43:37 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:43:38 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
08:43:38 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:43:39 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
08:43:39 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:43:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
08:43:40 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
08:43:40 INFO - Found existing installation: requests 1.2.3
08:43:40 INFO - Uninstalling requests-1.2.3:
08:43:40 INFO - Successfully uninstalled requests-1.2.3
08:43:40 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:43:40 INFO - Return code: 0
08:43:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
08:43:40 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:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:43:40 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:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:43:40 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 0x20d82d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2453930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194', '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:43:40 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:43:40 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:43:40 INFO - Using env: {'DISPLAY': ':0',
08:43:40 INFO - 'HOME': '/home/cltbld',
08:43:40 INFO - 'LANG': 'en_US.UTF-8',
08:43:40 INFO - 'LANGUAGE': 'en_US:en',
08:43:40 INFO - 'LOGNAME': 'cltbld',
08:43:40 INFO - 'MAIL': '/var/mail/cltbld',
08:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:40 INFO - 'MOZ_NO_REMOTE': '1',
08:43:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:40 INFO - 'NO_EM_RESTART': '1',
08:43:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:40 INFO - 'PWD': '/builds/slave/test',
08:43:40 INFO - 'SHELL': '/bin/bash',
08:43:40 INFO - 'SHLVL': '1',
08:43:40 INFO - 'TERM': 'linux',
08:43:40 INFO - 'TMOUT': '86400',
08:43:40 INFO - 'USER': 'cltbld',
08:43:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:40 INFO - '_': '/tools/buildbot/bin/python'}
08:43:41 INFO - Ignoring indexes: https://pypi.python.org/simple
08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
08:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
08:43:41 INFO - Return code: 0
08:43:41 INFO - Running post-action listener: _resource_record_post_action
08:43:41 INFO - Running post-action listener: _start_resource_monitoring
08:43:41 INFO - Starting resource monitoring.
08:43:41 INFO - [mozharness: 2017-11-03 15:43:41.285943Z] Finished create-virtualenv step (success)
08:43:41 INFO - [mozharness: 2017-11-03 15:43:41.286447Z] Running install step.
08:43:41 INFO - Running pre-action listener: _resource_record_pre_action
08:43:41 INFO - Running main action method: install
08:43:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:43:41 INFO - Reading from file tmpfile_stdout
08:43:41 INFO - Reading from file tmpfile_stderr
08:43:41 INFO - Detecting whether we're running mozinstall >=1.0...
08:43:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
08:43:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
08:43:42 INFO - Reading from file tmpfile_stdout
08:43:42 INFO - Output received:
08:43:42 INFO - Usage: mozinstall [options] installer
08:43:42 INFO - Options:
08:43:42 INFO - -h, --help show this help message and exit
08:43:42 INFO - -d DEST, --destination=DEST
08:43:42 INFO - Directory to install application into. [default:
08:43:42 INFO - "/builds/slave/test"]
08:43:42 INFO - --app=APP Application being installed. [default: firefox]
08:43:42 INFO - mkdir: /builds/slave/test/build/application
08:43:42 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:43:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
08:43:56 INFO - Reading from file tmpfile_stdout
08:43:56 INFO - Output received:
08:43:56 INFO - /builds/slave/test/build/application/firefox/firefox
08:43:56 INFO - Running post-action listener: _resource_record_post_action
08:43:56 INFO - [mozharness: 2017-11-03 15:43:56.934269Z] Finished install step (success)
08:43:56 INFO - [mozharness: 2017-11-03 15:43:56.934514Z] Running setup-mitmproxy step.
08:43:56 INFO - Running pre-action listener: _resource_record_pre_action
08:43:56 INFO - Running main action method: setup_mitmproxy
08:43:56 INFO - Skipping: mitmproxy is not required
08:43:56 INFO - Running post-action listener: _resource_record_post_action
08:43:56 INFO - [mozharness: 2017-11-03 15:43:56.935683Z] Finished setup-mitmproxy step (success)
08:43:56 INFO - [mozharness: 2017-11-03 15:43:56.935902Z] Running run-tests step.
08:43:56 INFO - Running pre-action listener: _resource_record_pre_action
08:43:56 INFO - Running pre-action listener: _set_gcov_prefix
08:43:56 INFO - Running main action method: run_tests
08:43:56 WARNING - Try message not found.
08:43:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
08:43:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
08:43:56 INFO - Python 2.7.3
08:43:56 INFO - Return code: 0
08:43:56 INFO - grabbing minidump binary from tooltool
08:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:43:56 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 0x2450ad0>, '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 0x24519d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2451e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
08:43:56 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:43:56 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:43:56 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:43:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
08:43:57 INFO - Return code: 0
08:43:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
08:43:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
08:43:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
08:43:57 INFO - ENV: RUST_BACKTRACE is now full
08:43:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
08:43:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
08:43:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] in /builds/slave/test/build
08:43:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta-Non-PGO --suite g4-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log
08:43:57 INFO - Using env: {'DISPLAY': ':0',
08:43:57 INFO - 'HOME': '/home/cltbld',
08:43:57 INFO - 'LANG': 'en_US.UTF-8',
08:43:57 INFO - 'LANGUAGE': 'en_US:en',
08:43:57 INFO - 'LOGNAME': 'cltbld',
08:43:57 INFO - 'MAIL': '/var/mail/cltbld',
08:43:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:43:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:43:57 INFO - 'MOZ_NO_REMOTE': '1',
08:43:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:43:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:43:57 INFO - 'NO_EM_RESTART': '1',
08:43:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:43:57 INFO - 'PWD': '/builds/slave/test',
08:43:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:43:57 INFO - 'RUST_BACKTRACE': 'full',
08:43:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:43:57 INFO - 'SHELL': '/bin/bash',
08:43:57 INFO - 'SHLVL': '1',
08:43:57 INFO - 'TERM': 'linux',
08:43:57 INFO - 'TMOUT': '86400',
08:43:57 INFO - 'USER': 'cltbld',
08:43:57 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:43:57 INFO - '_': '/tools/buildbot/bin/python'}
08:43:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta-Non-PGO', '--suite', 'g4-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log'] with output_timeout 3600
08:43:57 INFO - mozversion application_buildid: 20171103142628
08:43:57 INFO - mozversion application_changeset: e65a20ee3a063aa94ea61059b80dce6bc065fa91
08:43:57 INFO - mozversion application_display_name: Firefox
08:43:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
08:43:57 INFO - mozversion application_name: Firefox
08:43:57 INFO - mozversion application_remotingname: firefox
08:43:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta
08:43:57 INFO - mozversion application_vendor: Mozilla
08:43:57 INFO - mozversion application_version: 58.0
08:43:57 INFO - mozversion platform_buildid: 20171103142628
08:43:57 INFO - mozversion platform_changeset: e65a20ee3a063aa94ea61059b80dce6bc065fa91
08:43:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta
08:43:57 INFO - mozversion platform_version: 58.0
08:43:57 INFO - using testdate: 1509723837
08:43:57 INFO - actual date: 1509723837
08:43:57 INFO - starting webserver on 'localhost:33188'
08:43:57 INFO - SUITE-START | Running 3 tests
08:43:57 INFO - TEST-START | basic_compositor_video
08:43:57 INFO - Initialising browser for basic_compositor_video test...
08:43:57 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33188/getInfo.html -profile /tmp/tmpfaZTX8/profile
08:43:57 INFO - TEST-INFO | started process 3767 (/builds/slave/test/build/application/firefox/firefox http://localhost:33188/getInfo.html)
08:44:06 INFO - TEST-INFO | 3767: exit 0
08:44:06 INFO - Browser initialized.
08:44:06 INFO - Running cycle 1/1 for basic_compositor_video test...
08:44:06 INFO - Using env: {'DISPLAY': ':0',
08:44:06 INFO - 'HOME': '/home/cltbld',
08:44:06 INFO - 'JSGC_DISABLE_POISONING': '1',
08:44:06 INFO - 'LANG': 'en_US.UTF-8',
08:44:06 INFO - 'LANGUAGE': 'en_US:en',
08:44:06 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:44:06 INFO - 'LOGNAME': 'cltbld',
08:44:06 INFO - 'MAIL': '/var/mail/cltbld',
08:44:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:44:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:44:06 INFO - 'MOZ_CRASHREPORTER': '1',
08:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:44:06 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:44:06 INFO - 'MOZ_NO_REMOTE': '1',
08:44:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:44:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:44:06 INFO - 'NO_EM_RESTART': '1',
08:44:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:44:06 INFO - 'PWD': '/builds/slave/test',
08:44:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:44:06 INFO - 'RUST_BACKTRACE': 'full',
08:44:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:44:06 INFO - 'SHELL': '/bin/bash',
08:44:06 INFO - 'SHLVL': '1',
08:44:06 INFO - 'TERM': 'linux',
08:44:06 INFO - 'TMOUT': '86400',
08:44:06 INFO - 'USER': 'cltbld',
08:44:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:44:06 INFO - '_': '/tools/buildbot/bin/python'}
08:44:06 INFO - TEST-INFO | started process 4043 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfaZTX8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
08:44:07 INFO - PID 4043 |
08:44:08 INFO - PID 4043 | (/builds/slave/test/build/application/firefox/firefox:4093): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:44:08 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6874971878515186 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6717836257309941 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.741442251886245 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7029999999999996 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 3.847480769230769 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.14156314699793 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.484976771196286 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4726736111111083 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5885102420856585 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.652415254237283 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.101245791245791 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 9.527476190476193 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.99102564102565 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.586190476190481 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.298299180327882 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.980269461077855 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.675350194552509 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.870978260869576 ms/frame
08:44:55 INFO - PID 4043 |
08:44:55 INFO - PID 4043 | Cycle 1(1): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:45:41 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.679787234042553 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6695742904841404 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.6991619479048699 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7322987012987014 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9904454787234047 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1676874999999995 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.5101228070175425 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.503809106830121 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640996240601508 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.467240437158465 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.41751736111112 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.365544041450773 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.909509090909083 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.314149484536081 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398409090909094 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.051746987951786 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.451660305343507 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05345303867405 ms/frame
08:45:41 INFO - PID 4043 |
08:45:41 INFO - PID 4043 | Cycle 1(2): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:46:27 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6793676552881927 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687906588824022 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7403828306264502 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7593315743183815 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.155394736842105 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.041151515151513 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.610126353790613 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.490855148342063 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.681931818181821 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589287709497207 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.034197324414711 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.00125 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.993553113553107 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.52644736842106 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.400909090909078 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.91029761904762 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.450820610687016 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.116583333333336 ms/frame
08:46:27 INFO - PID 4043 |
08:46:27 INFO - PID 4043 | Cycle 1(3): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:47:13 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.677479038569033 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713700918964078 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7333448873483537 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.725707506471096 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.02710067114094 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9221862745098015 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.413879408418657 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3399499165275475 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6199999999999966 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.619662921348321 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.383356401384086 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.931366120218572 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.15639405204461 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.582513227513234 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.297131147540984 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.127363636363633 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.365681818181821 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.240449438202223 ms/frame
08:47:13 INFO - PID 4043 |
08:47:13 INFO - PID 4043 | Cycle 1(4): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:47:59 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6799552071668533 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6709565580618213 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.748481934731935 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7044804088586025 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1214354395604405 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.099477459016393 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.4328718535469087 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.344515050167225 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.671143667296793 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5869832402234625 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.002083333333333 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.004500000000007 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.154981412639414 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527499999999993 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.556841004184097 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835739644970445 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.321018867924527 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.642553191489377 ms/frame
08:47:59 INFO - PID 4043 |
08:47:59 INFO - PID 4043 | Cycle 1(5): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:48:46 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6816676008968612 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670208333333332 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7216092943201373 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.712898116438356 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.06581978319783 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8023574144486707 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.464497690531177 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3407262103505864 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.726803435114506 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.510688705234163 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 9.775097719869713 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 9.80757352941176 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.072232472324727 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.53013157894736 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.658396624472578 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.767294117647072 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586544401544405 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.1772346368715 ms/frame
08:48:46 INFO - PID 4043 |
08:48:46 INFO - PID 4043 | Cycle 1(6): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:49:07 INFO - PID 4043 |
08:49:07 INFO - PID 4043 | (/builds/slave/test/build/application/firefox/firefox:4161): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:49:07 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6788304420817013 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.670267111853088 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7395623188405795 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.717489270386266 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9222875816993477 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158419958419956 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.4451320321469607 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3683333333333287 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.339012455516017 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.143136246786633 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.718571428571432 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.15375634517767 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.989926739926739 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.417708333333318 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.502041666666658 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.697660818713468 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.409524714828905 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.98928571428569 ms/frame
08:49:32 INFO - PID 4043 |
08:49:32 INFO - PID 4043 | Cycle 1(7): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:50:18 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6800839865621502 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669874791318865 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7478450786255098 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.708484201537147 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071309362279512 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.7969449715370014 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.3823337091319043 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3457859531772556 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.445072595281301 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5888268156424665 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.068791946308725 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.001050000000014 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.991080586080594 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.696631016042764 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347242798353928 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835147928994079 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.540538461538464 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.933196721311484 ms/frame
08:50:18 INFO - PID 4043 |
08:50:18 INFO - PID 4043 | Cycle 1(8): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:51:04 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6801091825307952 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669461602671118 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7637389770723104 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7197850386930358 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.099180327868853 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0004599999999995 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.5421251475796933 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4202307692307676 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.660584905660379 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.48141095890411 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.491241258741267 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.206556122448983 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.0314705882353 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.52757894736841 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.299118852459019 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054186746987924 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.541807692307703 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113055555555547 ms/frame
08:51:04 INFO - PID 4043 |
08:51:04 INFO - PID 4043 | Cycle 1(9): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:51:50 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6801147816349384 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6674875 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7598680351906157 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7590105540897096 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033387096774193 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1413354037267105 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.5629156769596184 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.466152512998269 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.475392335766422 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.618721910112358 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.036438127090303 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 9.757268292682935 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.113666666666676 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.527473684210532 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.245306122448973 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.12548484848486 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.278157894736834 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.753494623655921 ms/frame
08:51:50 INFO - PID 4043 |
08:51:50 INFO - PID 4043 | Cycle 1(10): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:52:36 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.678439597315436 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6673291666666668 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.728110599078341 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7155317324185244 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 3.886204663212435 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.18567991631799 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.5935269461077834 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.535565371024737 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.619419475655431 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.465382513661197 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 9.804869281045743 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.206989795918366 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.072140221402226 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.53163157894734 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.295286885245913 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.562369942196536 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.497950191570867 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.813567567567578 ms/frame
08:52:36 INFO - PID 4043 |
08:52:36 INFO - PID 4043 | Cycle 1(11): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:53:22 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6810252100840337 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6670583333333335 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.738647161066049 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.704510221465077 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115473251028805 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.100543032786886 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_startup = 3.4179840546697036 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1_inclip = 3.368257575757574 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693481973434535 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5576666666666625 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_startup = 10.491013986013987 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.480p.60fps.webm_scale_2_inclip = 10.155812182741116 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.032224264705894 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.532263157894723 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397004132231393 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.69935672514622 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.237752808988748 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.871684782608693 ms/frame
08:53:22 INFO - PID 4043 |
08:53:22 INFO - PID 4043 | Cycle 1(12): loaded http://localhost:33188/tests/video/video_playback.html (next: http://localhost:33188/tests/video/video_playback.html)
08:53:23 INFO - PID 4043 | __start_tp_report
08:53:23 INFO - PID 4043 | _x_x_mozilla_page_load
08:53:23 INFO - PID 4043 | _x_x_mozilla_page_load_details
08:53:23 INFO - PID 4043 | |i|pagename|runs|
08:53:23 INFO - PID 4043 | |0;240p.120fps.mp4_scale_1_startup;1.6874971878515186;1.679787234042553;1.6793676552881927;1.677479038569033;1.6799552071668533;1.6816676008968612;1.6788304420817013;1.6800839865621502;1.6801091825307952;1.6801147816349384;1.678439597315436;1.6810252100840337
08:53:23 INFO - PID 4043 | |1;240p.120fps.mp4_scale_1_inclip;1.6717836257309941;1.6695742904841404;1.6687906588824022;1.6713700918964078;1.6709565580618213;1.6670208333333332;1.670267111853088;1.669874791318865;1.669461602671118;1.6674875;1.6673291666666668;1.6670583333333335
08:53:23 INFO - PID 4043 | |2;240p.120fps.mp4_scale_1.1_startup;1.741442251886245;1.6991619479048699;1.7403828306264502;1.7333448873483537;1.748481934731935;1.7216092943201373;1.7395623188405795;1.7478450786255098;1.7637389770723104;1.7598680351906157;1.728110599078341;1.738647161066049
08:53:23 INFO - PID 4043 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7029999999999996;1.7322987012987014;1.7593315743183815;1.725707506471096;1.7044804088586025;1.712898116438356;1.717489270386266;1.708484201537147;1.7197850386930358;1.7590105540897096;1.7155317324185244;1.704510221465077
08:53:23 INFO - PID 4043 | |4;240p.120fps.mp4_scale_2_startup;3.847480769230769;3.9904454787234047;4.155394736842105;4.02710067114094;4.1214354395604405;4.06581978319783;3.9222875816993477;4.071309362279512;4.099180327868853;4.033387096774193;3.886204663212435;4.115473251028805
08:53:23 INFO - PID 4043 | |5;240p.120fps.mp4_scale_2_inclip;4.14156314699793;4.1676874999999995;4.041151515151513;3.9221862745098015;4.099477459016393;3.8023574144486707;4.158419958419956;3.7969449715370014;4.0004599999999995;4.1413354037267105;4.18567991631799;4.100543032786886
08:53:23 INFO - PID 4043 | |6;480p.60fps.webm_scale_1_startup;3.484976771196286;3.5101228070175425;3.610126353790613;3.413879408418657;3.4328718535469087;3.464497690531177;3.4451320321469607;3.3823337091319043;3.5421251475796933;3.5629156769596184;3.5935269461077834;3.4179840546697036
08:53:23 INFO - PID 4043 | |7;480p.60fps.webm_scale_1_inclip;3.4726736111111083;3.503809106830121;3.490855148342063;3.3399499165275475;3.344515050167225;3.3407262103505864;3.3683333333333287;3.3457859531772556;3.4202307692307676;3.466152512998269;3.535565371024737;3.368257575757574
08:53:23 INFO - PID 4043 | |8;480p.60fps.webm_scale_1.1_startup;5.5885102420856585;5.640996240601508;5.681931818181821;5.6199999999999966;5.671143667296793;5.726803435114506;5.339012455516017;5.445072595281301;5.660584905660379;5.475392335766422;5.619419475655431;5.693481973434535
08:53:23 INFO - PID 4043 | |9;480p.60fps.webm_scale_1.1_inclip;5.652415254237283;5.467240437158465;5.589287709497207;5.619662921348321;5.5869832402234625;5.510688705234163;5.143136246786633;5.5888268156424665;5.48141095890411;5.618721910112358;5.465382513661197;5.5576666666666625
08:53:23 INFO - PID 4043 | |10;480p.60fps.webm_scale_2_startup;10.101245791245791;10.41751736111112;10.034197324414711;10.383356401384086;10.002083333333333;9.775097719869713;10.718571428571432;10.068791946308725;10.491241258741267;10.036438127090303;9.804869281045743;10.491013986013987
08:53:23 INFO - PID 4043 | |11;480p.60fps.webm_scale_2_inclip;9.527476190476193;10.365544041450773;10.00125;10.931366120218572;10.004500000000007;9.80757352941176;10.15375634517767;10.001050000000014;10.206556122448983;9.757268292682935;10.206989795918366;10.155812182741116
08:53:23 INFO - PID 4043 | |12;1080p.60fps.mp4_scale_1_startup;10.99102564102565;10.909509090909083;10.993553113553107;11.15639405204461;11.154981412639414;11.072232472324727;10.989926739926739;10.991080586080594;11.0314705882353;11.113666666666676;11.072140221402226;11.032224264705894
08:53:23 INFO - PID 4043 | |13;1080p.60fps.mp4_scale_1_inclip;10.586190476190481;10.314149484536081;10.52644736842106;10.582513227513234;10.527499999999993;10.53013157894736;10.417708333333318;10.696631016042764;10.52757894736841;10.527473684210532;10.53163157894734;10.532263157894723
08:53:23 INFO - PID 4043 | |14;1080p.60fps.mp4_scale_1.1_startup;12.298299180327882;12.398409090909094;12.400909090909078;12.297131147540984;12.556841004184097;12.658396624472578;12.502041666666658;12.347242798353928;12.299118852459019;12.245306122448973;12.295286885245913;12.397004132231393
08:53:23 INFO - PID 4043 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.980269461077855;12.051746987951786;11.91029761904762;12.127363636363633;11.835739644970445;11.767294117647072;11.697660818713468;11.835147928994079;12.054186746987924;12.12548484848486;11.562369942196536;11.69935672514622
08:53:23 INFO - PID 4043 | |16;1080p.60fps.mp4_scale_2_startup;11.675350194552509;11.451660305343507;11.450820610687016;11.365681818181821;11.321018867924527;11.586544401544405;11.409524714828905;11.540538461538464;11.541807692307703;11.278157894736834;11.497950191570867;11.237752808988748
08:53:23 INFO - PID 4043 | |17;1080p.60fps.mp4_scale_2_inclip;10.870978260869576;11.05345303867405;11.116583333333336;11.240449438202223;10.642553191489377;11.1772346368715;10.98928571428569;10.933196721311484;11.113055555555547;10.753494623655921;10.813567567567578;10.871684782608693
08:53:23 INFO - PID 4043 | __end_tp_report
08:53:23 INFO - PID 4043 | __start_cc_report
08:53:23 INFO - PID 4043 | _x_x_mozilla_cycle_collect,572
08:53:23 INFO - PID 4043 | __end_cc_report
08:53:23 INFO - PID 4043 | __startTimestamp1509724403225__endTimestamp
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | ------- Summary: start -------
08:53:23 INFO - PID 4043 | Number of tests: 18
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.1%) stddev-sans-first:0.00
08:53:23 INFO - PID 4043 | 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:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
08:53:23 INFO - PID 4043 | 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:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.74 Median:1.74 stddev:0.02 (1.0%) stddev-sans-first:0.02
08:53:23 INFO - PID 4043 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.8 1.8 1.7 1.7
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.72 Median:1.72 stddev:0.02 (1.1%) stddev-sans-first:0.02
08:53:23 INFO - PID 4043 | Values: 1.7 1.7 1.8 1.7 1.7 1.7 1.7 1.7 1.7 1.8 1.7 1.7
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.03 Median:4.07 stddev:0.10 (2.4%) stddev-sans-first:0.08
08:53:23 INFO - PID 4043 | Values: 3.8 4.0 4.2 4.0 4.1 4.1 3.9 4.1 4.1 4.0 3.9 4.1
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.05 Median:4.12 stddev:0.14 (3.4%) stddev-sans-first:0.14
08:53:23 INFO - PID 4043 | Values: 4.1 4.2 4.0 3.9 4.1 3.8 4.2 3.8 4.0 4.1 4.2 4.1
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.49 Median:3.50 stddev:0.08 (2.1%) stddev-sans-first:0.08
08:53:23 INFO - PID 4043 | Values: 3.5 3.5 3.6 3.4 3.4 3.5 3.4 3.4 3.5 3.6 3.6 3.4
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.42 Median:3.44 stddev:0.07 (2.1%) stddev-sans-first:0.07
08:53:23 INFO - PID 4043 | Values: 3.5 3.5 3.5 3.3 3.3 3.3 3.4 3.3 3.4 3.5 3.5 3.4
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.60 Median:5.65 stddev:0.12 (2.1%) stddev-sans-first:0.12
08:53:23 INFO - PID 4043 | Values: 5.6 5.6 5.7 5.6 5.7 5.7 5.3 5.4 5.7 5.5 5.6 5.7
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.52 Median:5.59 stddev:0.14 (2.4%) stddev-sans-first:0.14
08:53:23 INFO - PID 4043 | Values: 5.7 5.5 5.6 5.6 5.6 5.5 5.1 5.6 5.5 5.6 5.5 5.6
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.19 Median:10.45 stddev:0.30 (2.8%) stddev-sans-first:0.31
08:53:23 INFO - PID 4043 | Values: 10.1 10.4 10.0 10.4 10.0 9.8 10.7 10.1 10.5 10.0 9.8 10.5
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.09 Median:10.29 stddev:0.35 (3.4%) stddev-sans-first:0.32
08:53:23 INFO - PID 4043 | Values: 9.5 10.4 10.0 10.9 10.0 9.8 10.2 10.0 10.2 9.8 10.2 10.2
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.04 Median:11.05 stddev:0.07 (0.7%) stddev-sans-first:0.08
08:53:23 INFO - PID 4043 | Values: 11.0 10.9 11.0 11.2 11.2 11.1 11.0 11.0 11.0 11.1 11.1 11.0
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.53 Median:10.53 stddev:0.09 (0.9%) stddev-sans-first:0.09
08:53:23 INFO - PID 4043 | Values: 10.6 10.3 10.5 10.6 10.5 10.5 10.4 10.7 10.5 10.5 10.5 10.5
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.39 Median:12.40 stddev:0.12 (1.0%) stddev-sans-first:0.13
08:53:23 INFO - PID 4043 | Values: 12.3 12.4 12.4 12.3 12.6 12.7 12.5 12.3 12.3 12.2 12.3 12.4
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.89 Median:11.95 stddev:0.18 (1.5%) stddev-sans-first:0.19
08:53:23 INFO - PID 4043 | Values: 12.0 12.1 11.9 12.1 11.8 11.8 11.7 11.8 12.1 12.1 11.6 11.7
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.45 Median:11.47 stddev:0.13 (1.1%) stddev-sans-first:0.11
08:53:23 INFO - PID 4043 | Values: 11.7 11.5 11.5 11.4 11.3 11.6 11.4 11.5 11.5 11.3 11.5 11.2
08:53:23 INFO - PID 4043 |
08:53:23 INFO - PID 4043 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.96 Median:11.02 stddev:0.18 (1.7%) stddev-sans-first:0.19
08:53:23 INFO - PID 4043 | Values: 10.9 11.1 11.1 11.2 10.6 11.2 11.0 10.9 11.1 10.8 10.8 10.9
08:53:23 INFO - PID 4043 | -------- Summary: end --------
08:53:23 INFO - PID 4043 |
08:53:23 INFO - TEST-INFO | 4043: exit 0
08:53:23 INFO - TEST-OK | basic_compositor_video | took 566359ms
08:53:23 INFO - TEST-START | glvideo
08:53:23 INFO - Initialising browser for glvideo test...
08:53:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33188/getInfo.html -profile /tmp/tmpfZfjPI/profile
08:53:23 INFO - TEST-INFO | started process 5332 (/builds/slave/test/build/application/firefox/firefox http://localhost:33188/getInfo.html)
08:53:29 INFO - TEST-INFO | 5332: exit 0
08:53:29 INFO - Browser initialized.
08:53:29 INFO - Running cycle 1/1 for glvideo test...
08:53:29 INFO - Using env: {'DISPLAY': ':0',
08:53:29 INFO - 'HOME': '/home/cltbld',
08:53:29 INFO - 'JSGC_DISABLE_POISONING': '1',
08:53:29 INFO - 'LANG': 'en_US.UTF-8',
08:53:29 INFO - 'LANGUAGE': 'en_US:en',
08:53:29 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:53:29 INFO - 'LOGNAME': 'cltbld',
08:53:29 INFO - 'MAIL': '/var/mail/cltbld',
08:53:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:53:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:53:29 INFO - 'MOZ_CRASHREPORTER': '1',
08:53:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:53:29 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:53:29 INFO - 'MOZ_NO_REMOTE': '1',
08:53:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:53:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:53:29 INFO - 'NO_EM_RESTART': '1',
08:53:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:53:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:53:29 INFO - 'PWD': '/builds/slave/test',
08:53:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:53:29 INFO - 'RUST_BACKTRACE': 'full',
08:53:29 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:53:29 INFO - 'SHELL': '/bin/bash',
08:53:29 INFO - 'SHLVL': '1',
08:53:29 INFO - 'TERM': 'linux',
08:53:29 INFO - 'TMOUT': '86400',
08:53:29 INFO - 'USER': 'cltbld',
08:53:29 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:53:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:53:29 INFO - '_': '/tools/buildbot/bin/python'}
08:53:29 INFO - TEST-INFO | started process 5608 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfZfjPI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:53:30 INFO - PID 5608 |
08:53:30 INFO - PID 5608 | (/builds/slave/test/build/application/firefox/firefox:5658): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:53:30 INFO - PID 5608 |
08:53:32 INFO - PID 5608 | [talos glvideo result] Mean tick time across 100 ticks: 8.5324 ms
08:53:32 INFO - PID 5608 | Cycle 1(1): loaded http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html)
08:53:33 INFO - PID 5608 | [talos glvideo result] Mean tick time across 100 ticks: 6.6081 ms
08:53:33 INFO - PID 5608 | Cycle 1(2): loaded http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html)
08:53:34 INFO - PID 5608 | [talos glvideo result] Mean tick time across 100 ticks: 6.655449999999999 ms
08:53:34 INFO - PID 5608 | Cycle 1(3): loaded http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html)
08:53:36 INFO - PID 5608 | [talos glvideo result] Mean tick time across 100 ticks: 6.49345 ms
08:53:36 INFO - PID 5608 | Cycle 1(4): loaded http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html)
08:53:37 INFO - PID 5608 | [talos glvideo result] Mean tick time across 100 ticks: 6.593450000000001 ms
08:53:37 INFO - PID 5608 | Cycle 1(5): loaded http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:33188/tests/webgl/benchmarks/video/video_upload.html)
08:53:37 INFO - PID 5608 | __start_tp_report
08:53:37 INFO - PID 5608 | _x_x_mozilla_page_load
08:53:37 INFO - PID 5608 | _x_x_mozilla_page_load_details
08:53:37 INFO - PID 5608 | |i|pagename|runs|
08:53:37 INFO - PID 5608 | |0;Mean tick time across 100 ticks: ;8.5324;6.6081;6.655449999999999;6.49345;6.593450000000001
08:53:37 INFO - PID 5608 | __end_tp_report
08:53:37 INFO - PID 5608 | __start_cc_report
08:53:37 INFO - PID 5608 | _x_x_mozilla_cycle_collect,264
08:53:37 INFO - PID 5608 | __end_cc_report
08:53:37 INFO - PID 5608 | __startTimestamp1509724417767__endTimestamp
08:53:37 INFO - PID 5608 |
08:53:37 INFO - PID 5608 | ------- Summary: start -------
08:53:37 INFO - PID 5608 | Number of tests: 1
08:53:37 INFO - PID 5608 |
08:53:37 INFO - PID 5608 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.98 Median:6.61 stddev:0.87 (13.2%) stddev-sans-first:0.07
08:53:37 INFO - PID 5608 | Values: 8.5 6.6 6.7 6.5 6.6
08:53:37 INFO - PID 5608 | -------- Summary: end --------
08:53:37 INFO - PID 5608 |
08:53:38 INFO - PID 5608 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:53:38 INFO - TEST-INFO | 5608: exit 0
08:53:38 INFO - TEST-OK | glvideo | took 14598ms
08:53:38 INFO - TEST-START | displaylist_mutate
08:53:38 INFO - Initialising browser for displaylist_mutate test...
08:53:38 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:33188/getInfo.html -profile /tmp/tmpdvEkI2/profile
08:53:38 INFO - TEST-INFO | started process 5832 (/builds/slave/test/build/application/firefox/firefox http://localhost:33188/getInfo.html)
08:53:44 INFO - TEST-INFO | 5832: exit 0
08:53:44 INFO - Browser initialized.
08:53:44 INFO - Running cycle 1/1 for displaylist_mutate test...
08:53:44 INFO - Using env: {'DISPLAY': ':0',
08:53:44 INFO - 'HOME': '/home/cltbld',
08:53:44 INFO - 'JSGC_DISABLE_POISONING': '1',
08:53:44 INFO - 'LANG': 'en_US.UTF-8',
08:53:44 INFO - 'LANGUAGE': 'en_US:en',
08:53:44 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:53:44 INFO - 'LOGNAME': 'cltbld',
08:53:44 INFO - 'MAIL': '/var/mail/cltbld',
08:53:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:53:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:53:44 INFO - 'MOZ_CRASHREPORTER': '1',
08:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:53:44 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:53:44 INFO - 'MOZ_NO_REMOTE': '1',
08:53:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:53:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:53:44 INFO - 'NO_EM_RESTART': '1',
08:53:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:53:44 INFO - 'PWD': '/builds/slave/test',
08:53:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:53:44 INFO - 'RUST_BACKTRACE': 'full',
08:53:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:53:44 INFO - 'SHELL': '/bin/bash',
08:53:44 INFO - 'SHLVL': '1',
08:53:44 INFO - 'TERM': 'linux',
08:53:44 INFO - 'TMOUT': '86400',
08:53:44 INFO - 'USER': 'cltbld',
08:53:44 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194',
08:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:53:44 INFO - '_': '/tools/buildbot/bin/python'}
08:53:44 INFO - TEST-INFO | started process 6103 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdvEkI2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/layout/displaylist_mutate.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:53:45 INFO - PID 6103 |
08:53:45 INFO - PID 6103 | (/builds/slave/test/build/application/firefox/firefox:6153): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:53:45 INFO - PID 6103 |
08:53:54 INFO - PID 6103 | Cycle 1(1): loaded http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html)
08:54:02 INFO - PID 6103 | Cycle 1(2): loaded http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html)
08:54:03 INFO - PID 6103 | Waiting for idle-callback
08:54:11 INFO - PID 6103 | Cycle 1(3): loaded http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html)
08:54:20 INFO - PID 6103 | Cycle 1(4): loaded http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html)
08:54:29 INFO - PID 6103 | Cycle 1(5): loaded http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html (next: http://localhost:33188/tests/layout/benchmarks/displaylist_mutate.html)
08:54:29 INFO - PID 6103 | __start_tp_report
08:54:29 INFO - PID 6103 | _x_x_mozilla_page_load
08:54:29 INFO - PID 6103 | _x_x_mozilla_page_load_details
08:54:29 INFO - PID 6103 | |i|pagename|runs|
08:54:29 INFO - PID 6103 | |0;/displaylist_mutate.html;8114.424999999999;8202.53;8044.47;8146.295;8113.214999999999
08:54:29 INFO - PID 6103 | __end_tp_report
08:54:29 INFO - PID 6103 | __start_cc_report
08:54:29 INFO - PID 6103 | _x_x_mozilla_cycle_collect,194
08:54:29 INFO - PID 6103 | __end_cc_report
08:54:29 INFO - PID 6103 | __startTimestamp1509724469672__endTimestamp
08:54:29 INFO - PID 6103 |
08:54:29 INFO - PID 6103 | ------- Summary: start -------
08:54:29 INFO - PID 6103 | Number of tests: 1
08:54:29 INFO - PID 6103 |
08:54:29 INFO - PID 6103 | [#0] /displaylist_mutate.html Cycles:5 Average:8124.19 Median:8114.42 stddev:57.44 (0.7%) stddev-sans-first:66.02
08:54:29 INFO - PID 6103 | Values: 8114.4 8202.5 8044.5 8146.3 8113.2
08:54:29 INFO - PID 6103 | -------- Summary: end --------
08:54:29 INFO - PID 6103 |
08:54:30 INFO - PID 6103 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:54:30 INFO - TEST-INFO | 6103: exit 0
08:54:30 INFO - TEST-OK | displaylist_mutate | took 51963ms
08:54:30 INFO - SUITE-END | took 632s
08:54:30 INFO - Completed test suite (00:10:33)
08:54:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.565375169838424, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6874971878515186, 1.679787234042553, 1.6793676552881927, 1.677479038569033, 1.6799552071668533, 1.6816676008968612, 1.6788304420817013, 1.6800839865621502, 1.6801091825307952, 1.6801147816349384, 1.678439597315436, 1.6810252100840337], "value": 1.6799552071668533, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6717836257309941, 1.6695742904841404, 1.6687906588824022, 1.6713700918964078, 1.6709565580618213, 1.6670208333333332, 1.670267111853088, 1.669874791318865, 1.669461602671118, 1.6674875, 1.6673291666666668, 1.6670583333333335], "value": 1.669461602671118, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.741442251886245, 1.6991619479048699, 1.7403828306264502, 1.7333448873483537, 1.748481934731935, 1.7216092943201373, 1.7395623188405795, 1.7478450786255098, 1.7637389770723104, 1.7598680351906157, 1.728110599078341, 1.738647161066049], "value": 1.7395623188405795, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7029999999999996, 1.7322987012987014, 1.7593315743183815, 1.725707506471096, 1.7044804088586025, 1.712898116438356, 1.717489270386266, 1.708484201537147, 1.7197850386930358, 1.7590105540897096, 1.7155317324185244, 1.704510221465077], "value": 1.717489270386266, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.847480769230769, 3.9904454787234047, 4.155394736842105, 4.02710067114094, 4.1214354395604405, 4.06581978319783, 3.9222875816993477, 4.071309362279512, 4.099180327868853, 4.033387096774193, 3.886204663212435, 4.115473251028805], "value": 4.06581978319783, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.14156314699793, 4.1676874999999995, 4.041151515151513, 3.9221862745098015, 4.099477459016393, 3.8023574144486707, 4.158419958419956, 3.7969449715370014, 4.0004599999999995, 4.1413354037267105, 4.18567991631799, 4.100543032786886], "value": 4.099477459016393, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.484976771196286, 3.5101228070175425, 3.610126353790613, 3.413879408418657, 3.4328718535469087, 3.464497690531177, 3.4451320321469607, 3.3823337091319043, 3.5421251475796933, 3.5629156769596184, 3.5935269461077834, 3.4179840546697036], "value": 3.464497690531177, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4726736111111083, 3.503809106830121, 3.490855148342063, 3.3399499165275475, 3.344515050167225, 3.3407262103505864, 3.3683333333333287, 3.3457859531772556, 3.4202307692307676, 3.466152512998269, 3.535565371024737, 3.368257575757574], "value": 3.3683333333333287, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.5885102420856585, 5.640996240601508, 5.681931818181821, 5.6199999999999966, 5.671143667296793, 5.726803435114506, 5.339012455516017, 5.445072595281301, 5.660584905660379, 5.475392335766422, 5.619419475655431, 5.693481973434535], "value": 5.640996240601508, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.652415254237283, 5.467240437158465, 5.589287709497207, 5.619662921348321, 5.5869832402234625, 5.510688705234163, 5.143136246786633, 5.5888268156424665, 5.48141095890411, 5.618721910112358, 5.465382513661197, 5.5576666666666625], "value": 5.5576666666666625, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.101245791245791, 10.41751736111112, 10.034197324414711, 10.383356401384086, 10.002083333333333, 9.775097719869713, 10.718571428571432, 10.068791946308725, 10.491241258741267, 10.036438127090303, 9.804869281045743, 10.491013986013987], "value": 10.068791946308725, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.527476190476193, 10.365544041450773, 10.00125, 10.931366120218572, 10.004500000000007, 9.80757352941176, 10.15375634517767, 10.001050000000014, 10.206556122448983, 9.757268292682935, 10.206989795918366, 10.155812182741116], "value": 10.15375634517767, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.99102564102565, 10.909509090909083, 10.993553113553107, 11.15639405204461, 11.154981412639414, 11.072232472324727, 10.989926739926739, 10.991080586080594, 11.0314705882353, 11.113666666666676, 11.072140221402226, 11.032224264705894], "value": 11.032224264705894, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.586190476190481, 10.314149484536081, 10.52644736842106, 10.582513227513234, 10.527499999999993, 10.53013157894736, 10.417708333333318, 10.696631016042764, 10.52757894736841, 10.527473684210532, 10.53163157894734, 10.532263157894723], "value": 10.52757894736841, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.298299180327882, 12.398409090909094, 12.400909090909078, 12.297131147540984, 12.556841004184097, 12.658396624472578, 12.502041666666658, 12.347242798353928, 12.299118852459019, 12.245306122448973, 12.295286885245913, 12.397004132231393], "value": 12.397004132231393, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.980269461077855, 12.051746987951786, 11.91029761904762, 12.127363636363633, 11.835739644970445, 11.767294117647072, 11.697660818713468, 11.835147928994079, 12.054186746987924, 12.12548484848486, 11.562369942196536, 11.69935672514622], "value": 11.835739644970445, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.675350194552509, 11.451660305343507, 11.450820610687016, 11.365681818181821, 11.321018867924527, 11.586544401544405, 11.409524714828905, 11.540538461538464, 11.541807692307703, 11.278157894736834, 11.497950191570867, 11.237752808988748], "value": 11.450820610687016, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.870978260869576, 11.05345303867405, 11.116583333333336, 11.240449438202223, 10.642553191489377, 11.1772346368715, 10.98928571428569, 10.933196721311484, 11.113055555555547, 10.753494623655921, 10.813567567567578, 10.871684782608693], "value": 10.98928571428569, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8.5324, 6.6081, 6.655449999999999, 6.49345, 6.593450000000001], "value": 6.6007750000000005, "unit": "ms"}], "extraOptions": ["e10s"], "name": "glvideo", "alertThreshold": 2.0}, {"lowerIsBetter": true, "subtests": [{"name": "", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [8114.424999999999, 8202.53, 8044.47, 8146.295, 8113.214999999999], "value": 8129.754999999999, "unit": "ms"}], "extraOptions": ["e10s"], "name": "displaylist_mutate", "alertThreshold": 2.0}]}
08:54:30 INFO - Return code: 0
08:54:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
08:54:30 INFO - # TBPL SUCCESS #
08:54:30 INFO - Running post-action listener: _package_coverage_data
08:54:30 INFO - Running post-action listener: _resource_record_post_action
08:54:30 INFO - [mozharness: 2017-11-03 15:54:30.828721Z] Finished run-tests step (success)
08:54:30 INFO - Running post-run listener: _resource_record_post_run
08:54:30 INFO - Total resource usage - Wall time: 649s; CPU: 36.0%; Read bytes: 17522688; Write bytes: 309874688; Read time: 4988; Write time: 401576
08:54:30 INFO - TinderboxPrint: CPU usage
36.3%
08:54:30 INFO - TinderboxPrint: I/O read bytes / time
17,522,688 / 4,988
08:54:30 INFO - TinderboxPrint: I/O write bytes / time
309,874,688 / 401,576
08:54:30 INFO - TinderboxPrint: CPU idle
3,246.6 (63.7%)
08:54:30 INFO - TinderboxPrint: CPU system
310.6 (6.1%)
08:54:30 INFO - TinderboxPrint: CPU user
1,506.8 (29.6%)
08:54:30 INFO - TinderboxPrint: Swap in / out
0 / 0
08:54:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 3223552; Read time: 0; Write time: 540
08:54:30 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
08:54:30 INFO - run-tests - Wall time: 634s; CPU: 37.0%; Read bytes: 15536128; Write bytes: 292958208; Read time: 4884; Write time: 398460
08:54:31 INFO - Running post-run listener: _upload_blobber_files
08:54:31 INFO - Blob upload gear active.
08:54:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
08:54:31 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:54:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
08:54:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
08:54:31 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:54:31 INFO - SNIMissingWarning
08:54:31 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:54:31 INFO - InsecurePlatformWarning
08:54:31 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:54:31 INFO - SubjectAltNameWarning
08:54:31 INFO - (blobuploader) - INFO - Open directory for files ...
08:54:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_raw.log ...
08:54:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:54:31 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:54:31 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:54:31 INFO - InsecurePlatformWarning
08:54:31 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:54:31 INFO - SubjectAltNameWarning
08:54:32 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:54:32 INFO - InsecurePlatformWarning
08:54:32 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_raw.log: uploaded
08:54:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:54:32 INFO - (blobuploader) - INFO - Done attempting.
08:54:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
08:54:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:54:32 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:54:32 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:54:32 INFO - InsecurePlatformWarning
08:54:32 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:54:32 INFO - SubjectAltNameWarning
08:54:37 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:54:37 INFO - InsecurePlatformWarning
08:54:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:54:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:54:37 INFO - (blobuploader) - INFO - Done attempting.
08:54:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-e10s_errorsummary.log ...
08:54:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:54:37 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:54:38 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:54:38 INFO - InsecurePlatformWarning
08:54:38 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:54:38 INFO - SubjectAltNameWarning
08:54:38 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:54:38 INFO - InsecurePlatformWarning
08:54:38 INFO - (blobuploader) - INFO - TinderboxPrint: g4-e10s_errorsummary.log: uploaded
08:54:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:54:38 INFO - (blobuploader) - INFO - Done attempting.
08:54:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
08:54:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:54:38 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:54:38 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:54:38 INFO - InsecurePlatformWarning
08:54:39 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:54:39 INFO - SubjectAltNameWarning
08:54:39 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:54:39 INFO - InsecurePlatformWarning
08:54:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
08:54:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:54:39 INFO - (blobuploader) - INFO - Done attempting.
08:54:39 INFO - (blobuploader) - INFO - Iteration through files over.
08:54:39 INFO - Return code: 0
08:54:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
08:54:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
08:54:39 INFO - Setting buildbot property blobber_files to {"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f30080501281b5351a3ffd532c5234db209d565c7181a848584995d5c57b39e8eca7eff437d12a4869724bbc567c359ad2c0e959781096997b6561e6ca47cc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0c6597e12445c0f4d13fc1fb2ff5844eca3ad5e324924d10a6300428112ea3b7f49915c333091ded02a506b336c38edc1630e8e3d9b8912668c051c4f8c91bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8d68295a0867be1885f9206e524187915a94798862ae21ef71a3b8343a8d1fc8babc8a4145184c7ab5f3f244a40ab435df8145ccd697b8927d15212d5986c237", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8683f58f09e95946b0441444fd541be74d7be0aea8030fae8a1df376f4938d259277ec925ac43b3b90892ca70ea1051b1eb2cee009788b0c7cebdc2919b70bc8"}
08:54:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
08:54:39 INFO - Writing to file /builds/slave/test/properties/blobber_files
08:54:39 INFO - Contents:
08:54:39 INFO - blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f30080501281b5351a3ffd532c5234db209d565c7181a848584995d5c57b39e8eca7eff437d12a4869724bbc567c359ad2c0e959781096997b6561e6ca47cc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0c6597e12445c0f4d13fc1fb2ff5844eca3ad5e324924d10a6300428112ea3b7f49915c333091ded02a506b336c38edc1630e8e3d9b8912668c051c4f8c91bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8d68295a0867be1885f9206e524187915a94798862ae21ef71a3b8343a8d1fc8babc8a4145184c7ab5f3f244a40ab435df8145ccd697b8927d15212d5986c237", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8683f58f09e95946b0441444fd541be74d7be0aea8030fae8a1df376f4938d259277ec925ac43b3b90892ca70ea1051b1eb2cee009788b0c7cebdc2919b70bc8"}
08:54:39 INFO - Running post-run listener: copy_logs_to_upload_dir
08:54:39 INFO - Copying logs to upload dir...
08:54:39 INFO - mkdir: /builds/slave/test/build/upload/logs
08:54:39 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=717.146390
========= master_lag: 0.12 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 57 secs) (at 2017-11-03 08:54:40.094367) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-03 08:54:40.098457) =========
bash -c 'for file in `ls -1`; do cat $file; done'
in dir /builds/slave/test/properties (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test/properties
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f30080501281b5351a3ffd532c5234db209d565c7181a848584995d5c57b39e8eca7eff437d12a4869724bbc567c359ad2c0e959781096997b6561e6ca47cc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0c6597e12445c0f4d13fc1fb2ff5844eca3ad5e324924d10a6300428112ea3b7f49915c333091ded02a506b336c38edc1630e8e3d9b8912668c051c4f8c91bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8d68295a0867be1885f9206e524187915a94798862ae21ef71a3b8343a8d1fc8babc8a4145184c7ab5f3f244a40ab435df8145ccd697b8927d15212d5986c237", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8683f58f09e95946b0441444fd541be74d7be0aea8030fae8a1df376f4938d259277ec925ac43b3b90892ca70ea1051b1eb2cee009788b0c7cebdc2919b70bc8"}
build_url:https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011335
build_url: 'https://queue.taskcluster.net/v1/task/ejFas9P2QW2HGeFRR0OdGg/artifacts/public/build/target.tar.bz2'
blobber_files: '{"g4-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/55f30080501281b5351a3ffd532c5234db209d565c7181a848584995d5c57b39e8eca7eff437d12a4869724bbc567c359ad2c0e959781096997b6561e6ca47cc", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/0c6597e12445c0f4d13fc1fb2ff5844eca3ad5e324924d10a6300428112ea3b7f49915c333091ded02a506b336c38edc1630e8e3d9b8912668c051c4f8c91bb0", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8d68295a0867be1885f9206e524187915a94798862ae21ef71a3b8343a8d1fc8babc8a4145184c7ab5f3f244a40ab435df8145ccd697b8927d15212d5986c237", "g4-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta-Non-PGO/sha512/8683f58f09e95946b0441444fd541be74d7be0aea8030fae8a1df376f4938d259277ec925ac43b3b90892ca70ea1051b1eb2cee009788b0c7cebdc2919b70bc8"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-03 08:54:40.130015) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 08:54:40.130330) =========
rm -f oauth.txt
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['rm', '-f', 'oauth.txt']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1509721352.921668-433541194
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004498
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-03 08:54:40.211097) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-03 08:54:40.211401) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-03 08:54:40.211726) =========
========= Total master_lag: 0.38 =========