builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s-pgo
slave: talos-linux64-ix-012
starttime: 1510487025.41
results: success (0)
revision: ea2100fcee92171fbb7f5b9a83dda324ed448d48
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.412610) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.413297) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.413794) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.448034) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.448403) =========
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=2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-11-12 03:43:45-- 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% 72.2M=0s
2017-11-12 03:43:45 (72.2 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.135122
========= master_lag: 0.03 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.610823) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.611107) =========
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=2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020477
========= master_lag: 0.05 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.680153) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:45.680844) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ea2100fcee92171fbb7f5b9a83dda324ed448d48 --destination scripts --debug'
in dir /builds/slave/test/. (timeout 1200 secs)
watching logfiles {}
argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev ea2100fcee92171fbb7f5b9a83dda324ed448d48 --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=2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-11-12 03:43:45,730 truncating revision to first 12 chars
2017-11-12 03:43:45,730 Setting DEBUG logging.
2017-11-12 03:43:45,730 attempt 1/10
2017-11-12 03:43:45,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ea2100fcee92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-11-12 03:43:46,117 unpacking tar archive at: mozilla-central-ea2100fcee92/testing/mozharness/
program finished with exit code 0
elapsedTime=0.939824
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:46.641127) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:46.641471) =========
script_repo_revision: ea2100fcee92171fbb7f5b9a83dda324ed448d48
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:46.641856) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:46.642163) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-11-12 03:43:46.657947) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 59 secs) (at 2017-11-12 03:43:46.658388) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:43:46 INFO - MultiFileLogger online at 20171112 03:43:46 in /builds/slave/test
03:43:46 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox
03:43:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:43:46 INFO - {'append_to_log': False,
03:43:46 INFO - 'base_work_dir': '/builds/slave/test',
03:43:46 INFO - 'blob_upload_branch': 'Firefox',
03:43:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:43:46 INFO - 'branch': 'Firefox',
03:43:46 INFO - 'buildbot_json_path': 'buildprops.json',
03:43:46 INFO - 'code_coverage': False,
03:43:46 INFO - 'config_files': ('talos/linux_config.py',),
03:43:46 INFO - 'default_actions': ('clobber',
03:43:46 INFO - 'read-buildbot-config',
03:43:46 INFO - 'download-and-extract',
03:43:46 INFO - 'populate-webroot',
03:43:46 INFO - 'create-virtualenv',
03:43:46 INFO - 'install',
03:43:46 INFO - 'setup-mitmproxy',
03:43:46 INFO - 'run-tests'),
03:43:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:43:46 INFO - 'disable_ccov_upload': False,
03:43:46 INFO - 'disable_stylo': False,
03:43:46 INFO - 'download_minidump_stackwalk': True,
03:43:46 INFO - 'download_symbols': 'ondemand',
03:43:46 INFO - 'e10s': False,
03:43:46 INFO - 'enable_stylo': False,
03:43:46 INFO - 'enable_webrender': False,
03:43:46 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:43:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:43:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:43:46 INFO - 'gecko_profile': False,
03:43:46 INFO - 'gecko_profile_interval': 0,
03:43:46 INFO - 'installer_path': 'installer.exe',
03:43:46 INFO - 'jsd_code_coverage': False,
03:43:46 INFO - 'log_level': 'info',
03:43:46 INFO - 'log_name': 'talos',
03:43:46 INFO - 'log_to_console': True,
03:43:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:43:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:43:46 INFO - 'opt_config_files': (),
03:43:46 INFO - 'pip_index': False,
03:43:46 INFO - 'suite': 'g4-stylo-disabled-e10s',
03:43:46 INFO - 'system_bits': '32',
03:43:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:43:46 INFO - 'title': 'talos-linux64-ix-012',
03:43:46 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:43:46 INFO - 'use_talos_json': True,
03:43:46 INFO - 'verify': 'False',
03:43:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:43:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:43:46 INFO - 'work_dir': 'build'}
03:43:46 INFO - [mozharness: 2017-11-12 11:43:46.848664Z] Running clobber step.
03:43:46 INFO - Running pre-action listener: _resource_record_pre_action
03:43:46 INFO - Running main action method: clobber
03:43:46 INFO - rmtree: /builds/slave/test/build
03:43:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:43:47 INFO - Running post-action listener: _resource_record_post_action
03:43:47 INFO - [mozharness: 2017-11-12 11:43:47.924620Z] Finished clobber step (success)
03:43:47 INFO - [mozharness: 2017-11-12 11:43:47.924734Z] Running read-buildbot-config step.
03:43:47 INFO - Running pre-action listener: _resource_record_pre_action
03:43:47 INFO - Running main action method: read_buildbot_config
03:43:47 INFO - Using buildbot properties:
03:43:47 INFO - {
03:43:47 INFO - "project": "",
03:43:47 INFO - "product": "firefox",
03:43:47 INFO - "who": "btara@mozilla.com",
03:43:47 INFO - "installer_path": "public/build/target.tar.bz2",
03:43:47 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
03:43:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:43:47 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos g4-stylo-disabled-e10s",
03:43:47 INFO - "stage_platform": "linux64-pgo",
03:43:47 INFO - "basedir": "/builds/slave/test",
03:43:47 INFO - "buildnumber": 128,
03:43:47 INFO - "platform": "ubuntu64_hw",
03:43:47 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
03:43:47 INFO - "slavebuilddir": "test",
03:43:47 INFO - "taskId": "BJOi_DqfTTWcrfKR4crUtA",
03:43:47 INFO - "branch": "mozilla-central",
03:43:47 INFO - "script_repo_revision": "production",
03:43:47 INFO - "revision": "ea2100fcee92171fbb7f5b9a83dda324ed448d48",
03:43:47 INFO - "slavename": "talos-linux64-ix-012",
03:43:47 INFO - "repo_path": "mozilla-central"
03:43:47 INFO - }
03:43:47 INFO - Finding installer, test and symbols from parent task.
03:43:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BJOi_DqfTTWcrfKR4crUtA'}, attempt #1
03:43:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BJOi_DqfTTWcrfKR4crUtA'}, attempt #1
03:43:48 INFO - Task dependencies: fE9BQv80Q2Gaq-1XPsf_6A
03:43:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A'}, attempt #1
03:43:49 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2
03:43:49 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.test_packages.json
03:43:49 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.crashreporter-symbols.zip
03:43:49 INFO - Running post-action listener: _resource_record_post_action
03:43:49 INFO - [mozharness: 2017-11-12 11:43:49.257357Z] Finished read-buildbot-config step (success)
03:43:49 INFO - [mozharness: 2017-11-12 11:43:49.257572Z] Running download-and-extract step.
03:43:49 INFO - Running pre-action listener: _resource_record_pre_action
03:43:49 INFO - Running main action method: download_and_extract
03:43:49 INFO - mkdir: /builds/slave/test/build/tests
03:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:49 INFO - trying https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.test_packages.json
03:43:49 INFO - Downloading https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:43:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:43:49 INFO - Downloaded 989 bytes.
03:43:49 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:43:49 INFO - Using the following test package requirements:
03:43:49 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:43:49 INFO - u'common': [u'target.common.tests.zip'],
03:43:49 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:43:49 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:43:49 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:43:49 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:43:49 INFO - u'mozbase': [u'target.common.tests.zip'],
03:43:49 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:43:49 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:43:49 INFO - u'web-platform': [u'target.common.tests.zip',
03:43:49 INFO - u'target.web-platform.tests.tar.gz'],
03:43:49 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:43:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:43:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.common.tests.zip
03:43:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:43:49 INFO - Fetch https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.common.tests.zip into memory
03:43:52 INFO - Content-Length response header: 58507423
03:43:52 INFO - Bytes received: 58507423
03:44:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.talos.tests.zip
03:44:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:44:03 INFO - Fetch https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.talos.tests.zip into memory
03:44:04 INFO - Content-Length response header: 18772073
03:44:04 INFO - Bytes received: 18772073
03:44:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:05 INFO - trying https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2
03:44:05 INFO - Downloading https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:44:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:44:07 INFO - Downloaded 63322097 bytes.
03:44:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2
03:44:07 INFO - mkdir: /builds/slave/test/properties
03:44:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:44:07 INFO - Writing to file /builds/slave/test/properties/build_url
03:44:07 INFO - Contents:
03:44:07 INFO - build_url:https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2
03:44:07 INFO - Running post-action listener: _resource_record_post_action
03:44:07 INFO - Running post-action listener: find_tests_for_verification
03:44:07 INFO - Running post-action listener: set_extra_try_arguments
03:44:07 INFO - [mozharness: 2017-11-12 11:44:07.745785Z] Finished download-and-extract step (success)
03:44:07 INFO - [mozharness: 2017-11-12 11:44:07.745970Z] Running populate-webroot step.
03:44:07 INFO - Running pre-action listener: _resource_record_pre_action
03:44:07 INFO - Running main action method: populate_webroot
03:44:07 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:44:07 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['tresize',
03:44:07 INFO - 'tcanvasmark']},
03:44:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:44:07 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['dromaeo_css',
03:44:07 INFO - 'kraken']},
03:44:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:44:07 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:44:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'tests': ['damp', 'tps']},
03:44:07 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['damp', 'tps']},
03:44:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:44:07 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['dromaeo_dom']},
03:44:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video',
03:44:07 INFO - 'glvideo',
03:44:07 INFO - 'displaylist_mutate']},
03:44:07 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['basic_compositor_video',
03:44:07 INFO - 'glvideo']},
03:44:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:44:07 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['ts_paint_webext',
03:44:07 INFO - 'tp5o_webext']},
03:44:07 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:44:07 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:44:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:44:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:44:07 INFO - 'talos_options': ['--mitmproxy',
03:44:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:44:07 INFO - '--firstNonBlankPaint'],
03:44:07 INFO - 'tests': ['tp6_google_heavy',
03:44:07 INFO - 'tp6_youtube_heavy',
03:44:07 INFO - 'tp6_amazon_heavy',
03:44:07 INFO - 'tp6_facebook_heavy']},
03:44:07 INFO - 'other-e10s': {'tests': ['a11yr',
03:44:07 INFO - 'ts_paint',
03:44:07 INFO - 'tpaint',
03:44:07 INFO - 'sessionrestore',
03:44:07 INFO - 'sessionrestore_many_windows',
03:44:07 INFO - 'sessionrestore_no_auto_restore',
03:44:07 INFO - 'tabpaint',
03:44:07 INFO - 'cpstartup']},
03:44:07 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['a11yr',
03:44:07 INFO - 'ts_paint',
03:44:07 INFO - 'tpaint',
03:44:07 INFO - 'sessionrestore',
03:44:07 INFO - 'sessionrestore_many_windows',
03:44:07 INFO - 'sessionrestore_no_auto_restore',
03:44:07 INFO - 'tabpaint',
03:44:07 INFO - 'cpstartup']},
03:44:07 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:44:07 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:44:07 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['perf_reftest_singletons']},
03:44:07 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['perf_reftest']},
03:44:07 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:44:07 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['speedometer']},
03:44:07 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:44:07 INFO - 'tsvgr_opacity',
03:44:07 INFO - 'tart',
03:44:07 INFO - 'tscrollx',
03:44:07 INFO - 'tsvg_static']},
03:44:07 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['tsvgx',
03:44:07 INFO - 'tsvgr_opacity',
03:44:07 INFO - 'tart',
03:44:07 INFO - 'tscrollx',
03:44:07 INFO - 'tsvg_static']},
03:44:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:44:07 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'talos_options': ['--disable-stylo'],
03:44:07 INFO - 'tests': ['tp5o']},
03:44:07 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:44:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:44:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:44:07 INFO - 'talos_options': ['--mitmproxy',
03:44:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:44:07 INFO - '--firstNonBlankPaint'],
03:44:07 INFO - 'tests': ['tp6_google',
03:44:07 INFO - 'tp6_youtube',
03:44:07 INFO - 'tp6_amazon',
03:44:07 INFO - 'tp6_facebook']},
03:44:07 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:44:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:44:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:44:07 INFO - 'talos_options': ['--disable-stylo',
03:44:07 INFO - '--mitmproxy',
03:44:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:44:07 INFO - '--firstNonBlankPaint'],
03:44:07 INFO - 'tests': ['tp6_google',
03:44:07 INFO - 'tp6_youtube',
03:44:07 INFO - 'tp6_amazon',
03:44:07 INFO - 'tp6_facebook']},
03:44:07 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:44:07 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:44:07 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:44:07 INFO - 'talos_options': ['--stylo-threads=1',
03:44:07 INFO - '--mitmproxy',
03:44:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:44:07 INFO - '--firstNonBlankPaint'],
03:44:07 INFO - 'tests': ['tp6_google',
03:44:07 INFO - 'tp6_youtube',
03:44:07 INFO - 'tp6_amazon',
03:44:07 INFO - 'tp6_facebook']},
03:44:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'talos_options': ['--xperf_path',
03:44:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:44:07 INFO - 'tests': ['tp5n']},
03:44:07 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:44:07 INFO - 'talos_options': ['--disable-stylo',
03:44:07 INFO - '--xperf_path',
03:44:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:44:07 INFO - 'tests': ['tp5n']}}}
03:44:07 INFO - Running post-action listener: _resource_record_post_action
03:44:07 INFO - [mozharness: 2017-11-12 11:44:07.772136Z] Finished populate-webroot step (success)
03:44:07 INFO - [mozharness: 2017-11-12 11:44:07.772229Z] Running create-virtualenv step.
03:44:07 INFO - Running pre-action listener: _resource_record_pre_action
03:44:07 INFO - Running main action method: create_virtualenv
03:44:07 INFO - Creating virtualenv /builds/slave/test/build/venv
03:44:07 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
03:44:07 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:44:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:44:08 INFO - Using real prefix '/usr'
03:44:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:44:08 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:44:09 INFO - Installing setuptools, pip, wheel...done.
03:44:09 INFO - Return code: 0
03:44:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
03:44:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
03:44:09 INFO - Using env: {'DISPLAY': ':0',
03:44:09 INFO - 'HOME': '/home/cltbld',
03:44:09 INFO - 'LANG': 'en_US.UTF-8',
03:44:09 INFO - 'LANGUAGE': 'en_US:en',
03:44:09 INFO - 'LOGNAME': 'cltbld',
03:44:09 INFO - 'MAIL': '/var/mail/cltbld',
03:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:09 INFO - 'MOZ_NO_REMOTE': '1',
03:44:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:09 INFO - 'NO_EM_RESTART': '1',
03:44:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:09 INFO - 'PWD': '/builds/slave/test',
03:44:09 INFO - 'SHELL': '/bin/bash',
03:44:09 INFO - 'SHLVL': '1',
03:44:09 INFO - 'TERM': 'linux',
03:44:09 INFO - 'TMOUT': '86400',
03:44:09 INFO - 'USER': 'cltbld',
03:44:09 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:09 INFO - '_': '/tools/buildbot/bin/python'}
03:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:44:10 INFO - Return code: 0
03:44:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:44: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')]}
03:44:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44: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')]}
03:44:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44: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', '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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44: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', 'psutil>=3.1.1'] in /builds/slave/test/build
03:44: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 psutil>=3.1.1
03:44:10 INFO - Using env: {'DISPLAY': ':0',
03:44:10 INFO - 'HOME': '/home/cltbld',
03:44:10 INFO - 'LANG': 'en_US.UTF-8',
03:44:10 INFO - 'LANGUAGE': 'en_US:en',
03:44:10 INFO - 'LOGNAME': 'cltbld',
03:44:10 INFO - 'MAIL': '/var/mail/cltbld',
03:44:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:10 INFO - 'MOZ_NO_REMOTE': '1',
03:44:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:10 INFO - 'NO_EM_RESTART': '1',
03:44:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:10 INFO - 'PWD': '/builds/slave/test',
03:44:10 INFO - 'SHELL': '/bin/bash',
03:44:10 INFO - 'SHLVL': '1',
03:44:10 INFO - 'TERM': 'linux',
03:44:10 INFO - 'TMOUT': '86400',
03:44:10 INFO - 'USER': 'cltbld',
03:44:10 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:10 INFO - '_': '/tools/buildbot/bin/python'}
03:44:10 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:10 INFO - Collecting psutil>=3.1.1
03:44:11 INFO - Installing collected packages: psutil
03:44:11 INFO - Successfully installed psutil-3.1.1
03:44:11 INFO - Return code: 0
03:44:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
03:44:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
03:44:11 INFO - Using env: {'DISPLAY': ':0',
03:44:11 INFO - 'HOME': '/home/cltbld',
03:44:11 INFO - 'LANG': 'en_US.UTF-8',
03:44:11 INFO - 'LANGUAGE': 'en_US:en',
03:44:11 INFO - 'LOGNAME': 'cltbld',
03:44:11 INFO - 'MAIL': '/var/mail/cltbld',
03:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:11 INFO - 'MOZ_NO_REMOTE': '1',
03:44:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:11 INFO - 'NO_EM_RESTART': '1',
03:44:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:11 INFO - 'PWD': '/builds/slave/test',
03:44:11 INFO - 'SHELL': '/bin/bash',
03:44:11 INFO - 'SHLVL': '1',
03:44:11 INFO - 'TERM': 'linux',
03:44:11 INFO - 'TMOUT': '86400',
03:44:11 INFO - 'USER': 'cltbld',
03:44:11 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:11 INFO - '_': '/tools/buildbot/bin/python'}
03:44:12 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:12 INFO - Collecting mozsystemmonitor==0.3
03:44:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:44:13 INFO - Installing collected packages: mozsystemmonitor
03:44:13 INFO - Successfully installed mozsystemmonitor-0.3
03:44:13 INFO - Return code: 0
03:44:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:44:13 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')]}
03:44:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:13 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')]}
03:44:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:13 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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:13 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
03:44:13 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
03:44:13 INFO - Using env: {'DISPLAY': ':0',
03:44:13 INFO - 'HOME': '/home/cltbld',
03:44:13 INFO - 'LANG': 'en_US.UTF-8',
03:44:13 INFO - 'LANGUAGE': 'en_US:en',
03:44:13 INFO - 'LOGNAME': 'cltbld',
03:44:13 INFO - 'MAIL': '/var/mail/cltbld',
03:44:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:13 INFO - 'MOZ_NO_REMOTE': '1',
03:44:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:13 INFO - 'NO_EM_RESTART': '1',
03:44:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:13 INFO - 'PWD': '/builds/slave/test',
03:44:13 INFO - 'SHELL': '/bin/bash',
03:44:13 INFO - 'SHLVL': '1',
03:44:13 INFO - 'TERM': 'linux',
03:44:13 INFO - 'TMOUT': '86400',
03:44:13 INFO - 'USER': 'cltbld',
03:44:13 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:13 INFO - '_': '/tools/buildbot/bin/python'}
03:44:13 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:13 INFO - Collecting jsonschema==2.5.1
03:44:14 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:44:14 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:44:15 INFO - Installing collected packages: functools32, jsonschema
03:44:15 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:44:15 INFO - Return code: 0
03:44:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:44: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')]}
03:44:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44: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')]}
03:44:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:16 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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:16 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
03:44:16 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
03:44:16 INFO - Using env: {'DISPLAY': ':0',
03:44:16 INFO - 'HOME': '/home/cltbld',
03:44:16 INFO - 'LANG': 'en_US.UTF-8',
03:44:16 INFO - 'LANGUAGE': 'en_US:en',
03:44:16 INFO - 'LOGNAME': 'cltbld',
03:44:16 INFO - 'MAIL': '/var/mail/cltbld',
03:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:16 INFO - 'MOZ_NO_REMOTE': '1',
03:44:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:16 INFO - 'NO_EM_RESTART': '1',
03:44:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:16 INFO - 'PWD': '/builds/slave/test',
03:44:16 INFO - 'SHELL': '/bin/bash',
03:44:16 INFO - 'SHLVL': '1',
03:44:16 INFO - 'TERM': 'linux',
03:44:16 INFO - 'TMOUT': '86400',
03:44:16 INFO - 'USER': 'cltbld',
03:44:16 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:16 INFO - '_': '/tools/buildbot/bin/python'}
03:44:16 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:44:16 INFO - Return code: 0
03:44:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:44:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:16 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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:16 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
03:44:16 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
03:44:16 INFO - Using env: {'DISPLAY': ':0',
03:44:16 INFO - 'HOME': '/home/cltbld',
03:44:16 INFO - 'LANG': 'en_US.UTF-8',
03:44:16 INFO - 'LANGUAGE': 'en_US:en',
03:44:16 INFO - 'LOGNAME': 'cltbld',
03:44:16 INFO - 'MAIL': '/var/mail/cltbld',
03:44:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:16 INFO - 'MOZ_NO_REMOTE': '1',
03:44:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:16 INFO - 'NO_EM_RESTART': '1',
03:44:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:16 INFO - 'PWD': '/builds/slave/test',
03:44:16 INFO - 'SHELL': '/bin/bash',
03:44:16 INFO - 'SHLVL': '1',
03:44:16 INFO - 'TERM': 'linux',
03:44:16 INFO - 'TMOUT': '86400',
03:44:16 INFO - 'USER': 'cltbld',
03:44:16 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:16 INFO - '_': '/tools/buildbot/bin/python'}
03:44:16 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:16 INFO - Collecting blobuploader==1.2.4
03:44:17 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:44:18 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:44:20 INFO - Installing collected packages: requests, docopt, blobuploader
03:44:20 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:44:20 INFO - Return code: 0
03:44:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:20 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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:20 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
03:44:20 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
03:44:20 INFO - Using env: {'DISPLAY': ':0',
03:44:20 INFO - 'HOME': '/home/cltbld',
03:44:20 INFO - 'LANG': 'en_US.UTF-8',
03:44:20 INFO - 'LANGUAGE': 'en_US:en',
03:44:20 INFO - 'LOGNAME': 'cltbld',
03:44:20 INFO - 'MAIL': '/var/mail/cltbld',
03:44:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:20 INFO - 'MOZ_NO_REMOTE': '1',
03:44:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:20 INFO - 'NO_EM_RESTART': '1',
03:44:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:20 INFO - 'PWD': '/builds/slave/test',
03:44:20 INFO - 'SHELL': '/bin/bash',
03:44:20 INFO - 'SHLVL': '1',
03:44:20 INFO - 'TERM': 'linux',
03:44:20 INFO - 'TMOUT': '86400',
03:44:20 INFO - 'USER': 'cltbld',
03:44:20 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:20 INFO - '_': '/tools/buildbot/bin/python'}
03:44:20 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:20 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:44:21 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:44:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:44:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:44:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:44:22 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:44:22 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:44:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:44:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:44:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:44:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:44:23 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:44:23 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:44:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:44:24 INFO - Running setup.py install for manifestparser: started
03:44:24 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:44:24 INFO - Running setup.py install for mozcrash: started
03:44:24 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:44:24 INFO - Running setup.py install for mozdebug: started
03:44:24 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:44:24 INFO - Running setup.py install for mozdevice: started
03:44:25 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:44:25 INFO - Running setup.py install for mozfile: started
03:44:25 INFO - Running setup.py install for mozfile: finished with status 'done'
03:44:25 INFO - Running setup.py install for mozhttpd: started
03:44:25 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:44:25 INFO - Running setup.py install for mozinfo: started
03:44:25 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:44:25 INFO - Running setup.py install for mozInstall: started
03:44:25 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:44:25 INFO - Running setup.py install for mozleak: started
03:44:26 INFO - Running setup.py install for mozleak: finished with status 'done'
03:44:26 INFO - Running setup.py install for mozlog: started
03:44:26 INFO - Running setup.py install for mozlog: finished with status 'done'
03:44:26 INFO - Running setup.py install for moznetwork: started
03:44:26 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:44:26 INFO - Running setup.py install for mozprocess: started
03:44:26 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:44:26 INFO - Running setup.py install for mozprofile: started
03:44:26 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:44:26 INFO - Running setup.py install for mozrunner: started
03:44:27 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:44:27 INFO - Running setup.py install for mozscreenshot: started
03:44:27 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:44:27 INFO - Running setup.py install for moztest: started
03:44:27 INFO - Running setup.py install for moztest: finished with status 'done'
03:44:27 INFO - Running setup.py install for mozversion: started
03:44:27 INFO - Running setup.py install for mozversion: finished with status 'done'
03:44:28 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
03:44:28 INFO - Return code: 0
03:44:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:44:28 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')]}
03:44:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:28 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')]}
03:44:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:28 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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:28 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
03:44:28 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
03:44:28 INFO - Using env: {'DISPLAY': ':0',
03:44:28 INFO - 'HOME': '/home/cltbld',
03:44:28 INFO - 'LANG': 'en_US.UTF-8',
03:44:28 INFO - 'LANGUAGE': 'en_US:en',
03:44:28 INFO - 'LOGNAME': 'cltbld',
03:44:28 INFO - 'MAIL': '/var/mail/cltbld',
03:44:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:28 INFO - 'MOZ_NO_REMOTE': '1',
03:44:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:28 INFO - 'NO_EM_RESTART': '1',
03:44:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:28 INFO - 'PWD': '/builds/slave/test',
03:44:28 INFO - 'SHELL': '/bin/bash',
03:44:28 INFO - 'SHLVL': '1',
03:44:28 INFO - 'TERM': 'linux',
03:44:28 INFO - 'TMOUT': '86400',
03:44:28 INFO - 'USER': 'cltbld',
03:44:28 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:28 INFO - '_': '/tools/buildbot/bin/python'}
03:44:28 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:28 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:44:28 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))
03:44:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:44:29 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))
03:44:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:44:29 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))
03:44:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:44:29 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))
03:44:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:44:29 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))
03:44:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:44:29 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))
03:44:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:44:30 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))
03:44:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:44:30 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))
03:44:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:44:30 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))
03:44:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:44:30 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))
03:44:30 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:44:30 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))
03:44:30 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:44:31 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))
03:44:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:44:31 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))
03:44:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:44:31 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))
03:44:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:44:31 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))
03:44:31 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:44:31 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))
03:44:31 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:44:32 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))
03:44:32 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:44:33 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
03:44:33 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))
03:44:33 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))
03:44:33 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))
03:44:33 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))
03:44:33 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))
03:44:33 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))
03:44:33 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:44:34 INFO - Installing collected packages: six, blessings
03:44:34 INFO - Successfully installed blessings-1.6 six-1.10.0
03:44:34 INFO - Return code: 0
03:44:34 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:44:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:44:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:44:34 INFO - Reading from file tmpfile_stdout
03:44:34 INFO - Reading from file tmpfile_stderr
03:44:34 INFO - Current package versions:
03:44:34 INFO - blessings == 1.6
03:44:34 INFO - blobuploader == 1.2.4
03:44:34 INFO - docopt == 0.6.1
03:44:34 INFO - functools32 == 3.2.3.post2
03:44:34 INFO - jsonschema == 2.5.1
03:44:34 INFO - manifestparser == 1.2
03:44:34 INFO - mozInstall == 1.14
03:44:34 INFO - mozcrash == 1.0
03:44:34 INFO - mozdebug == 0.1
03:44:34 INFO - mozdevice == 0.51
03:44:34 INFO - mozfile == 1.2
03:44:34 INFO - mozhttpd == 0.7
03:44:34 INFO - mozinfo == 0.10
03:44:34 INFO - mozleak == 0.1
03:44:34 INFO - mozlog == 3.5
03:44:34 INFO - moznetwork == 0.27
03:44:34 INFO - mozprocess == 0.25
03:44:34 INFO - mozprofile == 0.29
03:44:34 INFO - mozrunner == 6.13
03:44:34 INFO - mozscreenshot == 0.1
03:44:34 INFO - mozsystemmonitor == 0.3
03:44:34 INFO - moztest == 0.8
03:44:34 INFO - mozversion == 1.4
03:44:34 INFO - psutil == 3.1.1
03:44:34 INFO - requests == 1.2.3
03:44:34 INFO - six == 1.10.0
03:44:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:34 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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:34 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
03:44:34 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
03:44:34 INFO - Using env: {'DISPLAY': ':0',
03:44:34 INFO - 'HOME': '/home/cltbld',
03:44:34 INFO - 'LANG': 'en_US.UTF-8',
03:44:34 INFO - 'LANGUAGE': 'en_US:en',
03:44:34 INFO - 'LOGNAME': 'cltbld',
03:44:34 INFO - 'MAIL': '/var/mail/cltbld',
03:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:34 INFO - 'MOZ_NO_REMOTE': '1',
03:44:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:34 INFO - 'NO_EM_RESTART': '1',
03:44:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:34 INFO - 'PWD': '/builds/slave/test',
03:44:34 INFO - 'SHELL': '/bin/bash',
03:44:34 INFO - 'SHLVL': '1',
03:44:34 INFO - 'TERM': 'linux',
03:44:34 INFO - 'TMOUT': '86400',
03:44:34 INFO - 'USER': 'cltbld',
03:44:34 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:34 INFO - '_': '/tools/buildbot/bin/python'}
03:44:35 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:35 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))
03:44:35 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))
03:44:35 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))
03:44:35 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))
03:44:35 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))
03:44:35 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))
03:44:35 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))
03:44:35 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))
03:44:35 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))
03:44:35 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:44:36 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:44:37 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
03:44:37 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))
03:44:37 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))
03:44:37 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))
03:44:37 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))
03:44:38 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
03:44:38 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:44:39 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
03:44:39 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:44:40 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
03:44:40 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:44:41 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
03:44:41 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
03:44:42 INFO - Found existing installation: requests 1.2.3
03:44:42 INFO - Uninstalling requests-1.2.3:
03:44:42 INFO - Successfully uninstalled requests-1.2.3
03:44:42 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
03:44:42 INFO - Return code: 0
03:44:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:44:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:44:42 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 0x1a9d2d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c75a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1e18930>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, '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': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709', '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
03:44:42 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
03:44:42 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
03:44:42 INFO - Using env: {'DISPLAY': ':0',
03:44:42 INFO - 'HOME': '/home/cltbld',
03:44:42 INFO - 'LANG': 'en_US.UTF-8',
03:44:42 INFO - 'LANGUAGE': 'en_US:en',
03:44:42 INFO - 'LOGNAME': 'cltbld',
03:44:42 INFO - 'MAIL': '/var/mail/cltbld',
03:44:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:42 INFO - 'MOZ_NO_REMOTE': '1',
03:44:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:42 INFO - 'NO_EM_RESTART': '1',
03:44:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:42 INFO - 'PWD': '/builds/slave/test',
03:44:42 INFO - 'SHELL': '/bin/bash',
03:44:42 INFO - 'SHLVL': '1',
03:44:42 INFO - 'TERM': 'linux',
03:44:42 INFO - 'TMOUT': '86400',
03:44:42 INFO - 'USER': 'cltbld',
03:44:42 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:44:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:42 INFO - '_': '/tools/buildbot/bin/python'}
03:44:42 INFO - Ignoring indexes: https://pypi.python.org/simple
03:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:44:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:44:42 INFO - Return code: 0
03:44:42 INFO - Running post-action listener: _resource_record_post_action
03:44:42 INFO - Running post-action listener: _start_resource_monitoring
03:44:42 INFO - Starting resource monitoring.
03:44:42 INFO - [mozharness: 2017-11-12 11:44:42.903121Z] Finished create-virtualenv step (success)
03:44:42 INFO - [mozharness: 2017-11-12 11:44:42.903621Z] Running install step.
03:44:42 INFO - Running pre-action listener: _resource_record_pre_action
03:44:42 INFO - Running main action method: install
03:44:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:44:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:44:43 INFO - Reading from file tmpfile_stdout
03:44:43 INFO - Reading from file tmpfile_stderr
03:44:43 INFO - Detecting whether we're running mozinstall >=1.0...
03:44:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:44:43 INFO - Reading from file tmpfile_stdout
03:44:43 INFO - Output received:
03:44:43 INFO - Usage: mozinstall [options] installer
03:44:43 INFO - Options:
03:44:43 INFO - -h, --help show this help message and exit
03:44:43 INFO - -d DEST, --destination=DEST
03:44:43 INFO - Directory to install application into. [default:
03:44:43 INFO - "/builds/slave/test"]
03:44:43 INFO - --app=APP Application being installed. [default: firefox]
03:44:43 INFO - mkdir: /builds/slave/test/build/application
03:44:43 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']
03:44:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:45:02 INFO - Reading from file tmpfile_stdout
03:45:02 INFO - Output received:
03:45:02 INFO - /builds/slave/test/build/application/firefox/firefox
03:45:02 INFO - Running post-action listener: _resource_record_post_action
03:45:02 INFO - [mozharness: 2017-11-12 11:45:02.388223Z] Finished install step (success)
03:45:02 INFO - [mozharness: 2017-11-12 11:45:02.388466Z] Running setup-mitmproxy step.
03:45:02 INFO - Running pre-action listener: _resource_record_pre_action
03:45:02 INFO - Running main action method: setup_mitmproxy
03:45:02 INFO - Skipping: mitmproxy is not required
03:45:02 INFO - Running post-action listener: _resource_record_post_action
03:45:02 INFO - [mozharness: 2017-11-12 11:45:02.389620Z] Finished setup-mitmproxy step (success)
03:45:02 INFO - [mozharness: 2017-11-12 11:45:02.389837Z] Running run-tests step.
03:45:02 INFO - Running pre-action listener: _resource_record_pre_action
03:45:02 INFO - Running pre-action listener: _set_gcov_prefix
03:45:02 INFO - Running main action method: run_tests
03:45:02 WARNING - Try message not found.
03:45:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:45:02 INFO - Python 2.7.3
03:45:02 INFO - Return code: 0
03:45:02 INFO - grabbing minidump binary from tooltool
03:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:45:02 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 0x1e15ad0>, '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 0x1e169d0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e16e60>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:45:02 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
03:45:02 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
03:45:02 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
03:45:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:45:02 INFO - Return code: 0
03:45:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:45:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:45:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:45:02 INFO - ENV: RUST_BACKTRACE is now full
03:45:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:45:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:45:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build
03:45:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log
03:45:02 INFO - Using env: {'DISPLAY': ':0',
03:45:02 INFO - 'HOME': '/home/cltbld',
03:45:02 INFO - 'LANG': 'en_US.UTF-8',
03:45:02 INFO - 'LANGUAGE': 'en_US:en',
03:45:02 INFO - 'LOGNAME': 'cltbld',
03:45:02 INFO - 'MAIL': '/var/mail/cltbld',
03:45:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:45:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:45:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:45:02 INFO - 'MOZ_NO_REMOTE': '1',
03:45:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:45:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:45:02 INFO - 'NO_EM_RESTART': '1',
03:45:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:45:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:45:02 INFO - 'PWD': '/builds/slave/test',
03:45:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:45:02 INFO - 'RUST_BACKTRACE': 'full',
03:45:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:45:02 INFO - 'SHELL': '/bin/bash',
03:45:02 INFO - 'SHLVL': '1',
03:45:02 INFO - 'TERM': 'linux',
03:45:02 INFO - 'TMOUT': '86400',
03:45:02 INFO - 'USER': 'cltbld',
03:45:02 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:45:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:45:02 INFO - '_': '/tools/buildbot/bin/python'}
03:45:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] with output_timeout 3600
03:45:02 INFO - mozversion application_buildid: 20171112101709
03:45:02 INFO - mozversion application_changeset: ea2100fcee92171fbb7f5b9a83dda324ed448d48
03:45:02 INFO - mozversion application_display_name: Nightly
03:45:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:45:02 INFO - mozversion application_name: Firefox
03:45:02 INFO - mozversion application_remotingname: firefox
03:45:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:45:02 INFO - mozversion application_vendor: Mozilla
03:45:02 INFO - mozversion application_version: 58.0a1
03:45:02 INFO - mozversion platform_buildid: 20171112101709
03:45:02 INFO - mozversion platform_changeset: ea2100fcee92171fbb7f5b9a83dda324ed448d48
03:45:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:45:02 INFO - mozversion platform_version: 58.0a1
03:45:02 INFO - using testdate: 1510487102
03:45:02 INFO - actual date: 1510487102
03:45:02 INFO - starting webserver on 'localhost:49935'
03:45:02 INFO - SUITE-START | Running 2 tests
03:45:02 INFO - TEST-START | basic_compositor_video
03:45:02 INFO - Initialising browser for basic_compositor_video test...
03:45:02 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49935/getInfo.html -profile /tmp/tmp0uf9UF/profile
03:45:02 INFO - TEST-INFO | started process 17686 (/builds/slave/test/build/application/firefox/firefox http://localhost:49935/getInfo.html)
03:45:07 INFO - TEST-INFO | 17686: exit 0
03:45:07 INFO - Browser initialized.
03:45:07 INFO - Running cycle 1/1 for basic_compositor_video test...
03:45:07 INFO - Using env: {'DISPLAY': ':0',
03:45:07 INFO - 'HOME': '/home/cltbld',
03:45:07 INFO - 'JSGC_DISABLE_POISONING': '1',
03:45:07 INFO - 'LANG': 'en_US.UTF-8',
03:45:07 INFO - 'LANGUAGE': 'en_US:en',
03:45:07 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:45:07 INFO - 'LOGNAME': 'cltbld',
03:45:07 INFO - 'MAIL': '/var/mail/cltbld',
03:45:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:45:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:45:07 INFO - 'MOZ_CRASHREPORTER': '1',
03:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:45:07 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:45:07 INFO - 'MOZ_NO_REMOTE': '1',
03:45:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:45:07 INFO - 'NO_EM_RESTART': '1',
03:45:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:45:07 INFO - 'PWD': '/builds/slave/test',
03:45:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:45:07 INFO - 'RUST_BACKTRACE': 'full',
03:45:07 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:45:07 INFO - 'SHELL': '/bin/bash',
03:45:07 INFO - 'SHLVL': '1',
03:45:07 INFO - 'STYLO_FORCE_DISABLED': '1',
03:45:07 INFO - 'TERM': 'linux',
03:45:07 INFO - 'TMOUT': '86400',
03:45:07 INFO - 'USER': 'cltbld',
03:45:07 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:45:07 INFO - '_': '/tools/buildbot/bin/python'}
03:45:07 INFO - TEST-INFO | started process 17963 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0uf9UF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
03:45:08 INFO - PID 17963 |
03:45:08 INFO - PID 17963 | (/builds/slave/test/build/application/firefox/firefox:18015): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:45:08 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6943506493506495 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668415346121768 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7085279043280184 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7025872340425523 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 3.974953642384106 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.073778004073317 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.409721590909089 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3449414715719086 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.505091743119262 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572729805013929 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.566038732394357 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 9.525309523809534 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.992967032967039 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.472251308900509 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.396694214876034 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.98005988023951 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.454332061068715 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812378378378384 ms/frame
03:45:54 INFO - PID 17963 |
03:45:54 INFO - PID 17963 | Cycle 1(1): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:46:41 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6884524479459764 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.670969089390142 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7175243274184318 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7071544368600688 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0221112600536175 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9771272365805186 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.41419226393629 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3564429530201383 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693320683111956 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.620196629213486 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.034765886287627 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 9.905866336633666 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.952937956204378 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.639441489361722 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.098346774193546 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.976407185628728 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.324056603773585 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.050745856353618 ms/frame
03:46:41 INFO - PID 17963 |
03:46:41 INFO - PID 17963 | Cycle 1(2): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:47:27 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6792249580302183 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6688824020016682 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7301874279123417 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.699409515717927 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.082993197278914 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.159999999999998 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.401468253968253 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.385896785109981 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.587513966480445 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6525423728813555 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 9.934817880794709 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 10.15403553299491 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.990238095238105 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.473900523560207 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.6615400843882 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.767176470588225 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.584305019305017 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.871222826086958 ms/frame
03:47:27 INFO - PID 17963 |
03:47:27 INFO - PID 17963 | Cycle 1(3): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:48:13 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6830005608524958 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.670162771285476 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.717469948483114 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7161449399656936 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 3.750787499999999 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.1597297297297295 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.433072082379864 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.356887583892614 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.651054613935968 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.512603305785126 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.138918918918915 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 9.852216748768473 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.9103090909091 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.529368421052641 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.50254166666667 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.633343023255843 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.366136363636377 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05110497237569 ms/frame
03:48:13 INFO - PID 17963 |
03:48:13 INFO - PID 17963 | Cycle 1(4): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:48:59 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6888632526730445 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6683402835696415 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.737611464968153 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7234194659776059 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.033252688172043 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.0159839357429705 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.593161676646708 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5659090909090883 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.693965844402272 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.378844086021506 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.312439862542949 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 10.585291005291 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.114388888888882 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.419869791666693 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.661329113924053 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.125939393939431 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.32203773584907 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812783783783773 ms/frame
03:48:59 INFO - PID 17963 |
03:48:59 INFO - PID 17963 | Cycle 1(5): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:49:45 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6803247480403136 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6688949124270227 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7252415181138583 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6970398642917723 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.060960757780784 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.992744510978047 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.394202488687783 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3616806722689088 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.771355769230769 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683423295454542 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.67809608540926 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 9.951517412935328 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.954288321167864 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.581851851851859 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.768574468085097 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.271257668711636 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49712643678161 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932677595628418 ms/frame
03:49:45 INFO - PID 17963 |
03:49:45 INFO - PID 17963 | Cycle 1(6): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:50:31 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681729260089686 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713868003341688 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7504842473745623 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.711475620188195 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.0991256830601115 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.12484536082474 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.58452210274791 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5529751332149213 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.5783364312267665 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572089136490254 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.172796610169486 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 9.526880952380955 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.951916058394163 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.642074468085102 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503020833333357 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.049879518072283 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.539980769230786 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.812405405405396 ms/frame
03:50:31 INFO - PID 17963 |
03:50:31 INFO - PID 17963 | Cycle 1(7): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:51:17 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6819534753363232 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6729055183946488 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.752666471962617 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.721648020654045 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 3.8611776061776064 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.176283924843425 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.4808236658932707 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4193333333333356 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.759011516314779 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.49736263736263 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.036806020066892 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 10.102601010101003 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.91152727272728 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.641542553191492 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346810699588483 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.12366666666666 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.497796934865884 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.053618784530396 ms/frame
03:51:17 INFO - PID 17963 |
03:51:17 INFO - PID 17963 | Cycle 1(8): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:52:04 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6792305540011192 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.669858096828047 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.715448827901658 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7040715502555372 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.167368055555557 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9459960552268223 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.425827625570776 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3452926421404694 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.628949343339594 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.589343575418986 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.347517241379306 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 10.311211340206185 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.99119047619048 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.699358288770048 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605840336134463 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.76667647058823 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.27900375939851 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.989945054945045 ms/frame
03:52:04 INFO - PID 17963 |
03:52:04 INFO - PID 17963 | Cycle 1(9): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:52:50 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6808907563025213 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6740376569037656 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7567447306791573 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.6968150975402876 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.173150208623089 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.937519685039367 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.4524856156501738 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3628235294117665 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650404896421849 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.54069252077562 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 9.8382786885246 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 10.261666666666656 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.07369003690036 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.643909574468086 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.397582644628084 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.498994252873546 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.628507751938008 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.051408839778967 ms/frame
03:52:50 INFO - PID 17963 |
03:52:50 INFO - PID 17963 | Cycle 1(10): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:53:36 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6839337822671157 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6684278565471224 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7375969889982628 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7144130248500422 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.185188284518829 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.184393305439329 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.5252878965922427 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3901440677966126 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.485585009140771 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.586927374301673 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 9.804624183006537 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 9.953233830845765 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.910509090909075 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.47141361256545 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.347242798353898 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.838698224852102 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.454160305343503 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.874076086956531 ms/frame
03:53:36 INFO - PID 17963 |
03:53:36 INFO - PID 17963 | Cycle 1(11): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:54:22 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_startup = 1.680347144456887 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682527105921603 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.703739352640545 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.697167090754878 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_startup = 4.178725626740948 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.177515657620039 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_startup = 3.493154831199072 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1_inclip = 3.3849915397631114 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.506146788990823 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.621011235955061 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_startup = 10.205680272108847 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.480p.60fps.webm_scale_2_inclip = 9.95228855721393 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_startup = 10.831444043321305 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.473089005235606 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.34810699588476 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.125939393939387 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.452404580152667 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.873423913043473 ms/frame
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | Cycle 1(12): loaded http://localhost:49935/tests/video/video_playback.html (next: http://localhost:49935/tests/video/video_playback.html)
03:54:22 INFO - PID 17963 | __start_tp_report
03:54:22 INFO - PID 17963 | _x_x_mozilla_page_load
03:54:22 INFO - PID 17963 | _x_x_mozilla_page_load_details
03:54:22 INFO - PID 17963 | |i|pagename|runs|
03:54:22 INFO - PID 17963 | |0;240p.120fps.mp4_scale_1_startup;1.6943506493506495;1.6884524479459764;1.6792249580302183;1.6830005608524958;1.6888632526730445;1.6803247480403136;1.681729260089686;1.6819534753363232;1.6792305540011192;1.6808907563025213;1.6839337822671157;1.680347144456887
03:54:22 INFO - PID 17963 | |1;240p.120fps.mp4_scale_1_inclip;1.668415346121768;1.670969089390142;1.6688824020016682;1.670162771285476;1.6683402835696415;1.6688949124270227;1.6713868003341688;1.6729055183946488;1.669858096828047;1.6740376569037656;1.6684278565471224;1.6682527105921603
03:54:22 INFO - PID 17963 | |2;240p.120fps.mp4_scale_1.1_startup;1.7085279043280184;1.7175243274184318;1.7301874279123417;1.717469948483114;1.737611464968153;1.7252415181138583;1.7504842473745623;1.752666471962617;1.715448827901658;1.7567447306791573;1.7375969889982628;1.703739352640545
03:54:22 INFO - PID 17963 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7025872340425523;1.7071544368600688;1.699409515717927;1.7161449399656936;1.7234194659776059;1.6970398642917723;1.711475620188195;1.721648020654045;1.7040715502555372;1.6968150975402876;1.7144130248500422;1.697167090754878
03:54:22 INFO - PID 17963 | |4;240p.120fps.mp4_scale_2_startup;3.974953642384106;4.0221112600536175;4.082993197278914;3.750787499999999;4.033252688172043;4.060960757780784;4.0991256830601115;3.8611776061776064;4.167368055555557;4.173150208623089;4.185188284518829;4.178725626740948
03:54:22 INFO - PID 17963 | |5;240p.120fps.mp4_scale_2_inclip;4.073778004073317;3.9771272365805186;4.159999999999998;4.1597297297297295;4.0159839357429705;3.992744510978047;4.12484536082474;4.176283924843425;3.9459960552268223;3.937519685039367;4.184393305439329;4.177515657620039
03:54:22 INFO - PID 17963 | |6;480p.60fps.webm_scale_1_startup;3.409721590909089;3.41419226393629;3.401468253968253;3.433072082379864;3.593161676646708;3.394202488687783;3.58452210274791;3.4808236658932707;3.425827625570776;3.4524856156501738;3.5252878965922427;3.493154831199072
03:54:22 INFO - PID 17963 | |7;480p.60fps.webm_scale_1_inclip;3.3449414715719086;3.3564429530201383;3.385896785109981;3.356887583892614;3.5659090909090883;3.3616806722689088;3.5529751332149213;3.4193333333333356;3.3452926421404694;3.3628235294117665;3.3901440677966126;3.3849915397631114
03:54:22 INFO - PID 17963 | |8;480p.60fps.webm_scale_1.1_startup;5.505091743119262;5.693320683111956;5.587513966480445;5.651054613935968;5.693965844402272;5.771355769230769;5.5783364312267665;5.759011516314779;5.628949343339594;5.650404896421849;5.485585009140771;5.506146788990823
03:54:22 INFO - PID 17963 | |9;480p.60fps.webm_scale_1.1_inclip;5.572729805013929;5.620196629213486;5.6525423728813555;5.512603305785126;5.378844086021506;5.683423295454542;5.572089136490254;5.49736263736263;5.589343575418986;5.54069252077562;5.586927374301673;5.621011235955061
03:54:22 INFO - PID 17963 | |10;480p.60fps.webm_scale_2_startup;10.566038732394357;10.034765886287627;9.934817880794709;10.138918918918915;10.312439862542949;10.67809608540926;10.172796610169486;10.036806020066892;10.347517241379306;9.8382786885246;9.804624183006537;10.205680272108847
03:54:22 INFO - PID 17963 | |11;480p.60fps.webm_scale_2_inclip;9.525309523809534;9.905866336633666;10.15403553299491;9.852216748768473;10.585291005291;9.951517412935328;9.526880952380955;10.102601010101003;10.311211340206185;10.261666666666656;9.953233830845765;9.95228855721393
03:54:22 INFO - PID 17963 | |12;1080p.60fps.mp4_scale_1_startup;10.992967032967039;10.952937956204378;10.990238095238105;10.9103090909091;11.114388888888882;10.954288321167864;10.951916058394163;10.91152727272728;10.99119047619048;11.07369003690036;10.910509090909075;10.831444043321305
03:54:22 INFO - PID 17963 | |13;1080p.60fps.mp4_scale_1_inclip;10.472251308900509;10.639441489361722;10.473900523560207;10.529368421052641;10.419869791666693;10.581851851851859;10.642074468085102;10.641542553191492;10.699358288770048;10.643909574468086;10.47141361256545;10.473089005235606
03:54:22 INFO - PID 17963 | |14;1080p.60fps.mp4_scale_1.1_startup;12.396694214876034;12.098346774193546;12.6615400843882;12.50254166666667;12.661329113924053;12.768574468085097;12.503020833333357;12.346810699588483;12.605840336134463;12.397582644628084;12.347242798353898;12.34810699588476
03:54:22 INFO - PID 17963 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.98005988023951;11.976407185628728;11.767176470588225;11.633343023255843;12.125939393939431;12.271257668711636;12.049879518072283;12.12366666666666;11.76667647058823;11.498994252873546;11.838698224852102;12.125939393939387
03:54:22 INFO - PID 17963 | |16;1080p.60fps.mp4_scale_2_startup;11.454332061068715;11.324056603773585;11.584305019305017;11.366136363636377;11.32203773584907;11.49712643678161;11.539980769230786;11.497796934865884;11.27900375939851;11.628507751938008;11.454160305343503;11.452404580152667
03:54:22 INFO - PID 17963 | |17;1080p.60fps.mp4_scale_2_inclip;10.812378378378384;11.050745856353618;10.871222826086958;11.05110497237569;10.812783783783773;10.932677595628418;10.812405405405396;11.053618784530396;10.989945054945045;11.051408839778967;10.874076086956531;10.873423913043473
03:54:22 INFO - PID 17963 | __end_tp_report
03:54:22 INFO - PID 17963 | __start_cc_report
03:54:22 INFO - PID 17963 | _x_x_mozilla_cycle_collect,601
03:54:22 INFO - PID 17963 | __end_cc_report
03:54:22 INFO - PID 17963 | __startTimestamp1510487662834__endTimestamp
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | ------- Summary: start -------
03:54:22 INFO - PID 17963 | Number of tests: 18
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.3%) stddev-sans-first:0.00
03:54:22 INFO - PID 17963 | 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
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#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
03:54:22 INFO - PID 17963 | 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
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.73 Median:1.73 stddev:0.02 (1.0%) stddev-sans-first:0.02
03:54:22 INFO - PID 17963 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.8 1.8 1.7 1.8 1.7 1.7
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.71 Median:1.71 stddev:0.01 (0.6%) stddev-sans-first:0.01
03:54:22 INFO - PID 17963 | 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
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.05 Median:4.09 stddev:0.13 (3.3%) stddev-sans-first:0.14
03:54:22 INFO - PID 17963 | Values: 4.0 4.0 4.1 3.8 4.0 4.1 4.1 3.9 4.2 4.2 4.2 4.2
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.08 Median:4.14 stddev:0.10 (2.4%) stddev-sans-first:0.10
03:54:22 INFO - PID 17963 | Values: 4.1 4.0 4.2 4.2 4.0 4.0 4.1 4.2 3.9 3.9 4.2 4.2
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.47 Median:3.47 stddev:0.07 (2.0%) stddev-sans-first:0.07
03:54:22 INFO - PID 17963 | Values: 3.4 3.4 3.4 3.4 3.6 3.4 3.6 3.5 3.4 3.5 3.5 3.5
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.40 Median:3.39 stddev:0.08 (2.3%) stddev-sans-first:0.08
03:54:22 INFO - PID 17963 | Values: 3.3 3.4 3.4 3.4 3.6 3.4 3.6 3.4 3.3 3.4 3.4 3.4
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.63 Median:5.65 stddev:0.10 (1.7%) stddev-sans-first:0.09
03:54:22 INFO - PID 17963 | Values: 5.5 5.7 5.6 5.7 5.7 5.8 5.6 5.8 5.6 5.7 5.5 5.5
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.57 Median:5.59 stddev:0.08 (1.4%) stddev-sans-first:0.08
03:54:22 INFO - PID 17963 | Values: 5.6 5.6 5.7 5.5 5.4 5.7 5.6 5.5 5.6 5.5 5.6 5.6
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:10.17 Median:10.46 stddev:0.27 (2.6%) stddev-sans-first:0.25
03:54:22 INFO - PID 17963 | Values: 10.6 10.0 9.9 10.1 10.3 10.7 10.2 10.0 10.3 9.8 9.8 10.2
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:10.01 Median:9.69 stddev:0.31 (3.2%) stddev-sans-first:0.28
03:54:22 INFO - PID 17963 | Values: 9.5 9.9 10.2 9.9 10.6 10.0 9.5 10.1 10.3 10.3 10.0 10.0
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:10.97 Median:10.97 stddev:0.08 (0.7%) stddev-sans-first:0.08
03:54:22 INFO - PID 17963 | Values: 11.0 11.0 11.0 10.9 11.1 11.0 11.0 10.9 11.0 11.1 10.9 10.8
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.56 Median:10.61 stddev:0.09 (0.9%) stddev-sans-first:0.09
03:54:22 INFO - PID 17963 | Values: 10.5 10.6 10.5 10.5 10.4 10.6 10.6 10.6 10.7 10.6 10.5 10.5
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.47 Median:12.50 stddev:0.19 (1.5%) stddev-sans-first:0.19
03:54:22 INFO - PID 17963 | Values: 12.4 12.1 12.7 12.5 12.7 12.8 12.5 12.3 12.6 12.4 12.3 12.3
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.93 Median:12.01 stddev:0.23 (1.9%) stddev-sans-first:0.24
03:54:22 INFO - PID 17963 | Values: 12.0 12.0 11.8 11.6 12.1 12.3 12.0 12.1 11.8 11.5 11.8 12.1
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.45 Median:11.48 stddev:0.11 (1.0%) stddev-sans-first:0.11
03:54:22 INFO - PID 17963 | Values: 11.5 11.3 11.6 11.4 11.3 11.5 11.5 11.5 11.3 11.6 11.5 11.5
03:54:22 INFO - PID 17963 |
03:54:22 INFO - PID 17963 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.93 Median:10.96 stddev:0.10 (0.9%) stddev-sans-first:0.10
03:54:22 INFO - PID 17963 | Values: 10.8 11.1 10.9 11.1 10.8 10.9 10.8 11.1 11.0 11.1 10.9 10.9
03:54:22 INFO - PID 17963 | -------- Summary: end --------
03:54:22 INFO - PID 17963 |
03:54:23 INFO - TEST-INFO | 17963: exit 0
03:54:23 INFO - TEST-OK | basic_compositor_video | took 560514ms
03:54:23 INFO - TEST-START | glvideo
03:54:23 INFO - Initialising browser for glvideo test...
03:54:23 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:49935/getInfo.html -profile /tmp/tmpcW6zoK/profile
03:54:23 INFO - TEST-INFO | started process 19187 (/builds/slave/test/build/application/firefox/firefox http://localhost:49935/getInfo.html)
03:54:28 INFO - TEST-INFO | 19187: exit 0
03:54:28 INFO - Browser initialized.
03:54:28 INFO - Running cycle 1/1 for glvideo test...
03:54:28 INFO - Using env: {'DISPLAY': ':0',
03:54:28 INFO - 'HOME': '/home/cltbld',
03:54:28 INFO - 'JSGC_DISABLE_POISONING': '1',
03:54:28 INFO - 'LANG': 'en_US.UTF-8',
03:54:28 INFO - 'LANGUAGE': 'en_US:en',
03:54:28 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:54:28 INFO - 'LOGNAME': 'cltbld',
03:54:28 INFO - 'MAIL': '/var/mail/cltbld',
03:54:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:54:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:54:28 INFO - 'MOZ_CRASHREPORTER': '1',
03:54:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:54:28 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:54:28 INFO - 'MOZ_NO_REMOTE': '1',
03:54:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:54:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:54:28 INFO - 'NO_EM_RESTART': '1',
03:54:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:54:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:54:28 INFO - 'PWD': '/builds/slave/test',
03:54:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:54:28 INFO - 'RUST_BACKTRACE': 'full',
03:54:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:54:28 INFO - 'SHELL': '/bin/bash',
03:54:28 INFO - 'SHLVL': '1',
03:54:28 INFO - 'STYLO_FORCE_DISABLED': '1',
03:54:28 INFO - 'TERM': 'linux',
03:54:28 INFO - 'TMOUT': '86400',
03:54:28 INFO - 'USER': 'cltbld',
03:54:28 INFO - 'XDG_SESSION_COOKIE': '2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709',
03:54:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:54:28 INFO - '_': '/tools/buildbot/bin/python'}
03:54:28 INFO - TEST-INFO | started process 19466 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcW6zoK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:54:29 INFO - PID 19466 |
03:54:29 INFO - PID 19466 | (/builds/slave/test/build/application/firefox/firefox:19517): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:54:29 INFO - PID 19466 |
03:54:30 INFO - PID 19466 | [talos glvideo result] Mean tick time across 100 ticks: 6.87465 ms
03:54:30 INFO - PID 19466 | Cycle 1(1): loaded http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html)
03:54:31 INFO - PID 19466 | [talos glvideo result] Mean tick time across 100 ticks: 6.487750000000001 ms
03:54:31 INFO - PID 19466 | Cycle 1(2): loaded http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html)
03:54:33 INFO - PID 19466 | [talos glvideo result] Mean tick time across 100 ticks: 6.57955 ms
03:54:33 INFO - PID 19466 | Cycle 1(3): loaded http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html)
03:54:34 INFO - PID 19466 | [talos glvideo result] Mean tick time across 100 ticks: 6.6781 ms
03:54:34 INFO - PID 19466 | Cycle 1(4): loaded http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html)
03:54:35 INFO - PID 19466 | [talos glvideo result] Mean tick time across 100 ticks: 6.62605 ms
03:54:35 INFO - PID 19466 | Cycle 1(5): loaded http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:49935/tests/webgl/benchmarks/video/video_upload.html)
03:54:36 INFO - PID 19466 | __start_tp_report
03:54:36 INFO - PID 19466 | _x_x_mozilla_page_load
03:54:36 INFO - PID 19466 | _x_x_mozilla_page_load_details
03:54:36 INFO - PID 19466 | |i|pagename|runs|
03:54:36 INFO - PID 19466 | |0;Mean tick time across 100 ticks: ;6.87465;6.487750000000001;6.57955;6.6781;6.62605
03:54:36 INFO - PID 19466 | __end_tp_report
03:54:36 INFO - PID 19466 | __start_cc_report
03:54:36 INFO - PID 19466 | _x_x_mozilla_cycle_collect,247
03:54:36 INFO - PID 19466 | __end_cc_report
03:54:36 INFO - PID 19466 | __startTimestamp1510487676173__endTimestamp
03:54:36 INFO - PID 19466 |
03:54:36 INFO - PID 19466 | ------- Summary: start -------
03:54:36 INFO - PID 19466 | Number of tests: 1
03:54:36 INFO - PID 19466 |
03:54:36 INFO - PID 19466 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.65 Median:6.63 stddev:0.14 (2.2%) stddev-sans-first:0.08
03:54:36 INFO - PID 19466 | Values: 6.9 6.5 6.6 6.7 6.6
03:54:36 INFO - PID 19466 | -------- Summary: end --------
03:54:36 INFO - PID 19466 |
03:54:36 INFO - PID 19466 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:54:36 INFO - TEST-INFO | 19466: exit 0
03:54:36 INFO - TEST-OK | glvideo | took 13463ms
03:54:36 INFO - SUITE-END | took 573s
03:54:37 INFO - Completed test suite (00:09:34)
03:54:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.568819211242345, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6943506493506495, 1.6884524479459764, 1.6792249580302183, 1.6830005608524958, 1.6888632526730445, 1.6803247480403136, 1.681729260089686, 1.6819534753363232, 1.6792305540011192, 1.6808907563025213, 1.6839337822671157, 1.680347144456887], "value": 1.681729260089686, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.668415346121768, 1.670969089390142, 1.6688824020016682, 1.670162771285476, 1.6683402835696415, 1.6688949124270227, 1.6713868003341688, 1.6729055183946488, 1.669858096828047, 1.6740376569037656, 1.6684278565471224, 1.6682527105921603], "value": 1.669858096828047, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7085279043280184, 1.7175243274184318, 1.7301874279123417, 1.717469948483114, 1.737611464968153, 1.7252415181138583, 1.7504842473745623, 1.752666471962617, 1.715448827901658, 1.7567447306791573, 1.7375969889982628, 1.703739352640545], "value": 1.7301874279123417, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7025872340425523, 1.7071544368600688, 1.699409515717927, 1.7161449399656936, 1.7234194659776059, 1.6970398642917723, 1.711475620188195, 1.721648020654045, 1.7040715502555372, 1.6968150975402876, 1.7144130248500422, 1.697167090754878], "value": 1.7071544368600688, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.974953642384106, 4.0221112600536175, 4.082993197278914, 3.750787499999999, 4.033252688172043, 4.060960757780784, 4.0991256830601115, 3.8611776061776064, 4.167368055555557, 4.173150208623089, 4.185188284518829, 4.178725626740948], "value": 4.082993197278914, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.073778004073317, 3.9771272365805186, 4.159999999999998, 4.1597297297297295, 4.0159839357429705, 3.992744510978047, 4.12484536082474, 4.176283924843425, 3.9459960552268223, 3.937519685039367, 4.184393305439329, 4.177515657620039], "value": 4.12484536082474, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.409721590909089, 3.41419226393629, 3.401468253968253, 3.433072082379864, 3.593161676646708, 3.394202488687783, 3.58452210274791, 3.4808236658932707, 3.425827625570776, 3.4524856156501738, 3.5252878965922427, 3.493154831199072], "value": 3.4524856156501738, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.3449414715719086, 3.3564429530201383, 3.385896785109981, 3.356887583892614, 3.5659090909090883, 3.3616806722689088, 3.5529751332149213, 3.4193333333333356, 3.3452926421404694, 3.3628235294117665, 3.3901440677966126, 3.3849915397631114], "value": 3.3849915397631114, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.505091743119262, 5.693320683111956, 5.587513966480445, 5.651054613935968, 5.693965844402272, 5.771355769230769, 5.5783364312267665, 5.759011516314779, 5.628949343339594, 5.650404896421849, 5.485585009140771, 5.506146788990823], "value": 5.650404896421849, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.572729805013929, 5.620196629213486, 5.6525423728813555, 5.512603305785126, 5.378844086021506, 5.683423295454542, 5.572089136490254, 5.49736263736263, 5.589343575418986, 5.54069252077562, 5.586927374301673, 5.621011235955061], "value": 5.586927374301673, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.566038732394357, 10.034765886287627, 9.934817880794709, 10.138918918918915, 10.312439862542949, 10.67809608540926, 10.172796610169486, 10.036806020066892, 10.347517241379306, 9.8382786885246, 9.804624183006537, 10.205680272108847], "value": 10.138918918918915, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.525309523809534, 9.905866336633666, 10.15403553299491, 9.852216748768473, 10.585291005291, 9.951517412935328, 9.526880952380955, 10.102601010101003, 10.311211340206185, 10.261666666666656, 9.953233830845765, 9.95228855721393], "value": 9.953233830845765, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.992967032967039, 10.952937956204378, 10.990238095238105, 10.9103090909091, 11.114388888888882, 10.954288321167864, 10.951916058394163, 10.91152727272728, 10.99119047619048, 11.07369003690036, 10.910509090909075, 10.831444043321305], "value": 10.952937956204378, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.472251308900509, 10.639441489361722, 10.473900523560207, 10.529368421052641, 10.419869791666693, 10.581851851851859, 10.642074468085102, 10.641542553191492, 10.699358288770048, 10.643909574468086, 10.47141361256545, 10.473089005235606], "value": 10.581851851851859, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.396694214876034, 12.098346774193546, 12.6615400843882, 12.50254166666667, 12.661329113924053, 12.768574468085097, 12.503020833333357, 12.346810699588483, 12.605840336134463, 12.397582644628084, 12.347242798353898, 12.34810699588476], "value": 12.50254166666667, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.98005988023951, 11.976407185628728, 11.767176470588225, 11.633343023255843, 12.125939393939431, 12.271257668711636, 12.049879518072283, 12.12366666666666, 11.76667647058823, 11.498994252873546, 11.838698224852102, 12.125939393939387], "value": 11.976407185628728, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.454332061068715, 11.324056603773585, 11.584305019305017, 11.366136363636377, 11.32203773584907, 11.49712643678161, 11.539980769230786, 11.497796934865884, 11.27900375939851, 11.628507751938008, 11.454160305343503, 11.452404580152667], "value": 11.454160305343503, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.812378378378384, 11.050745856353618, 10.871222826086958, 11.05110497237569, 10.812783783783773, 10.932677595628418, 10.812405405405396, 11.053618784530396, 10.989945054945045, 11.051408839778967, 10.874076086956531, 10.873423913043473], "value": 10.932677595628418, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [6.87465, 6.487750000000001, 6.57955, 6.6781, 6.62605], "value": 6.6028, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
03:54:37 INFO - Return code: 0
03:54:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:54:37 INFO - # TBPL SUCCESS #
03:54:37 INFO - Running post-action listener: _package_coverage_data
03:54:37 INFO - Running post-action listener: _resource_record_post_action
03:54:37 INFO - [mozharness: 2017-11-12 11:54:37.325549Z] Finished run-tests step (success)
03:54:37 INFO - Running post-run listener: _resource_record_post_run
03:54:37 INFO - Total resource usage - Wall time: 594s; CPU: 38.0%; Read bytes: 139264; Write bytes: 312688640; Read time: 192; Write time: 156904
03:54:37 INFO - TinderboxPrint: CPU usage
38.3%
03:54:37 INFO - TinderboxPrint: I/O read bytes / time
139,264 / 192
03:54:37 INFO - TinderboxPrint: I/O write bytes / time
312,688,640 / 156,904
03:54:37 INFO - TinderboxPrint: CPU idle
2,876.8 (61.7%)
03:54:37 INFO - TinderboxPrint: CPU system
303.1 (6.5%)
03:54:37 INFO - TinderboxPrint: CPU user
1,468.0 (31.5%)
03:54:37 INFO - TinderboxPrint: Swap in / out
0 / 0
03:54:37 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 7766016; Read time: 0; Write time: 6656
03:54:37 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:54:37 INFO - run-tests - Wall time: 575s; CPU: 39.0%; Read bytes: 139264; Write bytes: 304922624; Read time: 192; Write time: 150248
03:54:37 INFO - Running post-run listener: _upload_blobber_files
03:54:37 INFO - Blob upload gear active.
03:54:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:54:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
03:54:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
03:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
03:54:37 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:37 INFO - SNIMissingWarning
03: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
03:54:37 INFO - InsecurePlatformWarning
03: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.)
03:54:38 INFO - SubjectAltNameWarning
03:54:38 INFO - (blobuploader) - INFO - Open directory for files ...
03:54:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:54:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:54:38 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03: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
03:54:38 INFO - InsecurePlatformWarning
03: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.)
03:54:38 INFO - SubjectAltNameWarning
03:54:42 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:42 INFO - InsecurePlatformWarning
03:54:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:54:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:54:42 INFO - (blobuploader) - INFO - Done attempting.
03:54:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
03:54:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:54:42 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:54:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:43 INFO - InsecurePlatformWarning
03:54:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:54:43 INFO - SubjectAltNameWarning
03:54:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:43 INFO - InsecurePlatformWarning
03:54:43 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
03:54:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:54:43 INFO - (blobuploader) - INFO - Done attempting.
03:54:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:54:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:54:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:54:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:43 INFO - InsecurePlatformWarning
03:54:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:54:44 INFO - SubjectAltNameWarning
03:54:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:44 INFO - InsecurePlatformWarning
03:54:44 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:54:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:54:44 INFO - (blobuploader) - INFO - Done attempting.
03:54:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
03:54:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:54:44 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:54:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:44 INFO - InsecurePlatformWarning
03:54:44 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:54:44 INFO - SubjectAltNameWarning
03:54:45 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:54:45 INFO - InsecurePlatformWarning
03:54:45 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
03:54:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:54:45 INFO - (blobuploader) - INFO - Done attempting.
03:54:45 INFO - (blobuploader) - INFO - Iteration through files over.
03:54:45 INFO - Return code: 0
03:54:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:54:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:54:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26b47da5e83e107ec83425f9925c705fdcaa519a650c447f5f2baeef168b79c373c0a729bed0e7b596a3aba6affbd2486671cda68d6945fe8abb3d7279d28973", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cde0009fb768ba034d63deca75443c688b878c014f6c0322101e7e09eb11a85014e8b5eb9718f8e90a563e5fa3878bb34b4cc9682f5492d7c98a92b8fb1c9cc4", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0c12f99d19607a73437241a668b61f851412b07d4be44f685daeb0fc8838cac34f6283eecf5320805e26598c9e172c3ff585bc64c8bbb188c600c6d9993a2b1", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b57f50ed252e16d46d275b9d7f1cd36784607b6a20b1deda4aecd46a236f1ead49c518866ed40222cbfdd197eb0a1ca0c7073d5bbb3e9ab81cb0db0170a79fc"}
03:54:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:54:45 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:54:45 INFO - Contents:
03:54:45 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26b47da5e83e107ec83425f9925c705fdcaa519a650c447f5f2baeef168b79c373c0a729bed0e7b596a3aba6affbd2486671cda68d6945fe8abb3d7279d28973", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cde0009fb768ba034d63deca75443c688b878c014f6c0322101e7e09eb11a85014e8b5eb9718f8e90a563e5fa3878bb34b4cc9682f5492d7c98a92b8fb1c9cc4", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0c12f99d19607a73437241a668b61f851412b07d4be44f685daeb0fc8838cac34f6283eecf5320805e26598c9e172c3ff585bc64c8bbb188c600c6d9993a2b1", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b57f50ed252e16d46d275b9d7f1cd36784607b6a20b1deda4aecd46a236f1ead49c518866ed40222cbfdd197eb0a1ca0c7073d5bbb3e9ab81cb0db0170a79fc"}
03:54:45 INFO - Running post-run listener: copy_logs_to_upload_dir
03:54:45 INFO - Copying logs to upload dir...
03:54:45 INFO - mkdir: /builds/slave/test/build/upload/logs
03:54:45 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=659.246064
========= master_lag: 0.03 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 10 mins, 59 secs) (at 2017-11-12 03:54:45.938087) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-12 03:54:45.941780) =========
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=2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26b47da5e83e107ec83425f9925c705fdcaa519a650c447f5f2baeef168b79c373c0a729bed0e7b596a3aba6affbd2486671cda68d6945fe8abb3d7279d28973", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cde0009fb768ba034d63deca75443c688b878c014f6c0322101e7e09eb11a85014e8b5eb9718f8e90a563e5fa3878bb34b4cc9682f5492d7c98a92b8fb1c9cc4", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0c12f99d19607a73437241a668b61f851412b07d4be44f685daeb0fc8838cac34f6283eecf5320805e26598c9e172c3ff585bc64c8bbb188c600c6d9993a2b1", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b57f50ed252e16d46d275b9d7f1cd36784607b6a20b1deda4aecd46a236f1ead49c518866ed40222cbfdd197eb0a1ca0c7073d5bbb3e9ab81cb0db0170a79fc"}
build_url:https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011550
build_url: 'https://queue.taskcluster.net/v1/task/fE9BQv80Q2Gaq-1XPsf_6A/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/26b47da5e83e107ec83425f9925c705fdcaa519a650c447f5f2baeef168b79c373c0a729bed0e7b596a3aba6affbd2486671cda68d6945fe8abb3d7279d28973", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cde0009fb768ba034d63deca75443c688b878c014f6c0322101e7e09eb11a85014e8b5eb9718f8e90a563e5fa3878bb34b4cc9682f5492d7c98a92b8fb1c9cc4", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c0c12f99d19607a73437241a668b61f851412b07d4be44f685daeb0fc8838cac34f6283eecf5320805e26598c9e172c3ff585bc64c8bbb188c600c6d9993a2b1", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2b57f50ed252e16d46d275b9d7f1cd36784607b6a20b1deda4aecd46a236f1ead49c518866ed40222cbfdd197eb0a1ca0c7073d5bbb3e9ab81cb0db0170a79fc"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-11-12 03:54:45.972557) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:54:45.972880) =========
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=2c5dd2b0a310ecf02fd87158000001b4-1510486735.760836-1894178709
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004519
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-11-12 03:54:46.058525) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-12 03:54:46.058880) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-11-12 03:54:46.059282) =========
========= Total master_lag: 0.23 =========