builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s
slave: talos-linux64-ix-081
starttime: 1509464119.54
results: success (0)
revision: 0a7ff6e19bcc229500d92597fac9340d9bdef959
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.537195) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.537647) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.537916) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.579194) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.579618) =========
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=2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-31 08:35:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 226M=0s
2017-10-31 08:35:19 (226 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.147928
========= master_lag: 0.07 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.801862) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.802180) =========
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=2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.020799
========= master_lag: 0.03 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:19.855428) =========
========= Started 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-10-31 08:35:19.855766) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0a7ff6e19bcc229500d92597fac9340d9bdef959 --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 0a7ff6e19bcc229500d92597fac9340d9bdef959 --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=2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-31 08:35:19,913 truncating revision to first 12 chars
2017-10-31 08:35:19,913 Setting DEBUG logging.
2017-10-31 08:35:19,913 attempt 1/10
2017-10-31 08:35:19,913 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0a7ff6e19bcc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-31 08:35:20,438 unpacking tar archive at: mozilla-central-0a7ff6e19bcc/testing/mozharness/
program finished with exit code 0
elapsedTime=13.519715
========= master_lag: 0.02 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 13 secs) (at 2017-10-31 08:35:33.394260) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:33.394600) =========
script_repo_revision: 0a7ff6e19bcc229500d92597fac9340d9bdef959
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:33.394968) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:33.395237) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-31 08:35:33.409503) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 44 secs) (at 2017-10-31 08:35:33.409804) =========
/tools/buildbot/bin/python scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs)
watching logfiles {}
argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'g4-stylo-disabled-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO']
environment:
DISPLAY=:0
HOME=/home/cltbld
LANG=en_US.UTF-8
LANGUAGE=en_US:en
LOGNAME=cltbld
MAIL=/var/mail/cltbld
MOZ_CRASHREPORTER_NO_REPORT=1
MOZ_NO_REMOTE=1
NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript
NO_EM_RESTART=1
PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
PROPERTIES_FILE=/builds/slave/test/buildprops.json
PWD=/builds/slave/test
SHELL=/bin/bash
SHLVL=1
TERM=linux
TMOUT=86400
USER=cltbld
XDG_SESSION_COOKIE=2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
08:35:33 INFO - MultiFileLogger online at 20171031 08:35:33 in /builds/slave/test
08:35:33 INFO - Run as scripts/scripts/talos_script.py --suite g4-stylo-disabled-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO
08:35:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
08:35:33 INFO - {'append_to_log': False,
08:35:33 INFO - 'base_work_dir': '/builds/slave/test',
08:35:33 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
08:35:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
08:35:33 INFO - 'branch': 'Firefox-Non-PGO',
08:35:33 INFO - 'buildbot_json_path': 'buildprops.json',
08:35:33 INFO - 'code_coverage': False,
08:35:33 INFO - 'config_files': ('talos/linux_config.py',),
08:35:33 INFO - 'default_actions': ('clobber',
08:35:33 INFO - 'read-buildbot-config',
08:35:33 INFO - 'download-and-extract',
08:35:33 INFO - 'populate-webroot',
08:35:33 INFO - 'create-virtualenv',
08:35:33 INFO - 'install',
08:35:33 INFO - 'setup-mitmproxy',
08:35:33 INFO - 'run-tests'),
08:35:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
08:35:33 INFO - 'disable_ccov_upload': False,
08:35:33 INFO - 'disable_stylo': False,
08:35:33 INFO - 'download_minidump_stackwalk': True,
08:35:33 INFO - 'download_symbols': 'ondemand',
08:35:33 INFO - 'e10s': False,
08:35:33 INFO - 'enable_stylo': False,
08:35:33 INFO - 'enable_webrender': False,
08:35:33 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
08:35:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
08:35:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
08:35:33 INFO - 'gecko_profile': False,
08:35:33 INFO - 'gecko_profile_interval': 0,
08:35:33 INFO - 'installer_path': 'installer.exe',
08:35:33 INFO - 'log_level': 'info',
08:35:33 INFO - 'log_name': 'talos',
08:35:33 INFO - 'log_to_console': True,
08:35:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
08:35:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
08:35:33 INFO - 'opt_config_files': (),
08:35:33 INFO - 'pip_index': False,
08:35:33 INFO - 'suite': 'g4-stylo-disabled-e10s',
08:35:33 INFO - 'system_bits': '32',
08:35:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
08:35:33 INFO - 'title': 'talos-linux64-ix-081',
08:35:33 INFO - 'tooltool_cache': '/builds/tooltool_cache',
08:35:33 INFO - 'use_talos_json': True,
08:35:33 INFO - 'verify': 'False',
08:35:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
08:35:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
08:35:33 INFO - 'work_dir': 'build'}
08:35:33 INFO - [mozharness: 2017-10-31 15:35:33.595158Z] Running clobber step.
08:35:33 INFO - Running pre-action listener: _resource_record_pre_action
08:35:33 INFO - Running main action method: clobber
08:35:33 INFO - rmtree: /builds/slave/test/build
08:35:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
08:35:34 INFO - Running post-action listener: _resource_record_post_action
08:35:34 INFO - [mozharness: 2017-10-31 15:35:34.679027Z] Finished clobber step (success)
08:35:34 INFO - [mozharness: 2017-10-31 15:35:34.679123Z] Running read-buildbot-config step.
08:35:34 INFO - Running pre-action listener: _resource_record_pre_action
08:35:34 INFO - Running main action method: read_buildbot_config
08:35:34 INFO - Using buildbot properties:
08:35:34 INFO - {
08:35:34 INFO - "project": "",
08:35:34 INFO - "product": "firefox",
08:35:34 INFO - "who": "raliiev@mozilla.com",
08:35:34 INFO - "installer_path": "public/build/target.tar.bz2",
08:35:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
08:35:34 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
08:35:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-stylo-disabled-e10s",
08:35:34 INFO - "stage_platform": "linux64",
08:35:34 INFO - "basedir": "/builds/slave/test",
08:35:34 INFO - "buildnumber": 142,
08:35:34 INFO - "platform": "ubuntu64_hw",
08:35:34 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
08:35:34 INFO - "slavebuilddir": "test",
08:35:34 INFO - "taskId": "HYcm-POZQduovBEM1kq6yw",
08:35:34 INFO - "branch": "mozilla-central",
08:35:34 INFO - "script_repo_revision": "production",
08:35:34 INFO - "revision": "0a7ff6e19bcc229500d92597fac9340d9bdef959",
08:35:34 INFO - "slavename": "talos-linux64-ix-081",
08:35:34 INFO - "repo_path": "mozilla-central"
08:35:34 INFO - }
08:35:34 INFO - Finding installer, test and symbols from parent task.
08:35:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HYcm-POZQduovBEM1kq6yw'}, attempt #1
08:35:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HYcm-POZQduovBEM1kq6yw'}, attempt #1
08:35:35 INFO - Task dependencies: V1f3tdRkTwSW5Eiai-Wz0Q
08:35:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q'}, attempt #1
08:35:36 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2
08:35:36 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.test_packages.json
08:35:36 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.crashreporter-symbols.zip
08:35:36 INFO - Running post-action listener: _resource_record_post_action
08:35:36 INFO - [mozharness: 2017-10-31 15:35:36.411788Z] Finished read-buildbot-config step (success)
08:35:36 INFO - [mozharness: 2017-10-31 15:35:36.412008Z] Running download-and-extract step.
08:35:36 INFO - Running pre-action listener: _resource_record_pre_action
08:35:36 INFO - Running main action method: download_and_extract
08:35:36 INFO - mkdir: /builds/slave/test/build/tests
08:35:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:35:36 INFO - trying https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.test_packages.json
08:35:36 INFO - Downloading https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
08:35:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
08:35:36 INFO - Downloaded 989 bytes.
08:35:36 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
08:35:36 INFO - Using the following test package requirements:
08:35:36 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
08:35:36 INFO - u'common': [u'target.common.tests.zip'],
08:35:36 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
08:35:36 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
08:35:36 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
08:35:36 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
08:35:36 INFO - u'mozbase': [u'target.common.tests.zip'],
08:35:36 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
08:35:36 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
08:35:36 INFO - u'web-platform': [u'target.common.tests.zip',
08:35:36 INFO - u'target.web-platform.tests.tar.gz'],
08:35:36 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
08:35:36 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
08:35:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.common.tests.zip
08:35:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.common.tests.zip'}, attempt #1
08:35:36 INFO - Fetch https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.common.tests.zip into memory
08:35:56 INFO - Content-Length response header: 56359289
08:35:56 INFO - Bytes received: 56359289
08:36:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.talos.tests.zip
08:36:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.talos.tests.zip'}, attempt #1
08:36:07 INFO - Fetch https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.talos.tests.zip into memory
08:36:15 INFO - Content-Length response header: 18770379
08:36:15 INFO - Bytes received: 18770379
08:36: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')]}
08:36:16 INFO - trying https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2
08:36:16 INFO - Downloading https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
08:36:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
08:36:39 INFO - Downloaded 62826070 bytes.
08:36:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2
08:36:39 INFO - mkdir: /builds/slave/test/properties
08:36:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
08:36:39 INFO - Writing to file /builds/slave/test/properties/build_url
08:36:39 INFO - Contents:
08:36:39 INFO - build_url:https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2
08:36:39 INFO - Running post-action listener: _resource_record_post_action
08:36:39 INFO - Running post-action listener: find_tests_for_verification
08:36:39 INFO - Running post-action listener: set_extra_try_arguments
08:36:39 INFO - [mozharness: 2017-10-31 15:36:39.125112Z] Finished download-and-extract step (success)
08:36:39 INFO - [mozharness: 2017-10-31 15:36:39.125334Z] Running populate-webroot step.
08:36:39 INFO - Running pre-action listener: _resource_record_pre_action
08:36:39 INFO - Running main action method: populate_webroot
08:36:39 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
08:36:39 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['tresize',
08:36:39 INFO - 'tcanvasmark']},
08:36:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
08:36:39 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['dromaeo_css',
08:36:39 INFO - 'kraken']},
08:36:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:36:39 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
08:36:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'tests': ['damp', 'tps']},
08:36:39 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['damp', 'tps']},
08:36:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
08:36:39 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['dromaeo_dom']},
08:36:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
08:36:39 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['basic_compositor_video',
08:36:39 INFO - 'glvideo']},
08:36:39 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
08:36:39 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['ts_paint_webext',
08:36:39 INFO - 'tp5o_webext']},
08:36:39 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
08:36:39 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:36:39 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:36:39 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:36:39 INFO - 'talos_options': ['--mitmproxy',
08:36:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:36:39 INFO - '--firstNonBlankPaint'],
08:36:39 INFO - 'tests': ['tp6_google_heavy',
08:36:39 INFO - 'tp6_youtube_heavy',
08:36:39 INFO - 'tp6_amazon_heavy',
08:36:39 INFO - 'tp6_facebook_heavy']},
08:36:39 INFO - 'other-e10s': {'tests': ['a11yr',
08:36:39 INFO - 'ts_paint',
08:36:39 INFO - 'tpaint',
08:36:39 INFO - 'sessionrestore',
08:36:39 INFO - 'sessionrestore_many_windows',
08:36:39 INFO - 'sessionrestore_no_auto_restore',
08:36:39 INFO - 'tabpaint',
08:36:39 INFO - 'cpstartup']},
08:36:39 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['a11yr',
08:36:39 INFO - 'ts_paint',
08:36:39 INFO - 'tpaint',
08:36:39 INFO - 'sessionrestore',
08:36:39 INFO - 'sessionrestore_many_windows',
08:36:39 INFO - 'sessionrestore_no_auto_restore',
08:36:39 INFO - 'tabpaint',
08:36:39 INFO - 'cpstartup']},
08:36:39 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
08:36:39 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
08:36:39 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['perf_reftest_singletons']},
08:36:39 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['perf_reftest']},
08:36:39 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
08:36:39 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['speedometer']},
08:36:39 INFO - 'svgr-e10s': {'tests': ['tsvgx',
08:36:39 INFO - 'tsvgr_opacity',
08:36:39 INFO - 'tart',
08:36:39 INFO - 'tscrollx',
08:36:39 INFO - 'tsvg_static']},
08:36:39 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['tsvgx',
08:36:39 INFO - 'tsvgr_opacity',
08:36:39 INFO - 'tart',
08:36:39 INFO - 'tscrollx',
08:36:39 INFO - 'tsvg_static']},
08:36:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
08:36:39 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'talos_options': ['--disable-stylo'],
08:36:39 INFO - 'tests': ['tp5o']},
08:36:39 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:36:39 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:36:39 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:36:39 INFO - 'talos_options': ['--mitmproxy',
08:36:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:36:39 INFO - '--firstNonBlankPaint'],
08:36:39 INFO - 'tests': ['tp6_google',
08:36:39 INFO - 'tp6_youtube',
08:36:39 INFO - 'tp6_amazon',
08:36:39 INFO - 'tp6_facebook']},
08:36:39 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:36:39 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:36:39 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:36:39 INFO - 'talos_options': ['--disable-stylo',
08:36:39 INFO - '--mitmproxy',
08:36:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:36:39 INFO - '--firstNonBlankPaint'],
08:36:39 INFO - 'tests': ['tp6_google',
08:36:39 INFO - 'tp6_youtube',
08:36:39 INFO - 'tp6_amazon',
08:36:39 INFO - 'tp6_facebook']},
08:36:39 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
08:36:39 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
08:36:39 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
08:36:39 INFO - 'talos_options': ['--stylo-threads=1',
08:36:39 INFO - '--mitmproxy',
08:36:39 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
08:36:39 INFO - '--firstNonBlankPaint'],
08:36:39 INFO - 'tests': ['tp6_google',
08:36:39 INFO - 'tp6_youtube',
08:36:39 INFO - 'tp6_amazon',
08:36:39 INFO - 'tp6_facebook']},
08:36:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'talos_options': ['--xperf_path',
08:36:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:36:39 INFO - 'tests': ['tp5n']},
08:36:39 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
08:36:39 INFO - 'talos_options': ['--disable-stylo',
08:36:39 INFO - '--xperf_path',
08:36:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
08:36:39 INFO - 'tests': ['tp5n']}}}
08:36:39 INFO - Running post-action listener: _resource_record_post_action
08:36:39 INFO - [mozharness: 2017-10-31 15:36:39.147491Z] Finished populate-webroot step (success)
08:36:39 INFO - [mozharness: 2017-10-31 15:36:39.147589Z] Running create-virtualenv step.
08:36:39 INFO - Running pre-action listener: _resource_record_pre_action
08:36:39 INFO - Running main action method: create_virtualenv
08:36:39 INFO - Creating virtualenv /builds/slave/test/build/venv
08:36:39 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
08:36:39 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
08:36:39 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
08:36:39 INFO - Using real prefix '/usr'
08:36:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python
08:36:39 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
08:36:41 INFO - Installing setuptools, pip, wheel...done.
08:36:41 INFO - Return code: 0
08:36:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
08:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36:41 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
08:36:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
08:36:41 INFO - Using env: {'DISPLAY': ':0',
08:36:41 INFO - 'HOME': '/home/cltbld',
08:36:41 INFO - 'LANG': 'en_US.UTF-8',
08:36:41 INFO - 'LANGUAGE': 'en_US:en',
08:36:41 INFO - 'LOGNAME': 'cltbld',
08:36:41 INFO - 'MAIL': '/var/mail/cltbld',
08:36:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:41 INFO - 'MOZ_NO_REMOTE': '1',
08:36:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:41 INFO - 'NO_EM_RESTART': '1',
08:36:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:41 INFO - 'PWD': '/builds/slave/test',
08:36:41 INFO - 'SHELL': '/bin/bash',
08:36:41 INFO - 'SHLVL': '1',
08:36:41 INFO - 'TERM': 'linux',
08:36:41 INFO - 'TMOUT': '86400',
08:36:41 INFO - 'USER': 'cltbld',
08:36:41 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:41 INFO - '_': '/tools/buildbot/bin/python'}
08:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple
08:36:42 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
08:36:42 INFO - Return code: 0
08:36:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
08:36: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')]}
08:36:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36: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')]}
08:36:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36: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', '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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36: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', 'psutil>=3.1.1'] in /builds/slave/test/build
08:36: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 psutil>=3.1.1
08:36:42 INFO - Using env: {'DISPLAY': ':0',
08:36:42 INFO - 'HOME': '/home/cltbld',
08:36:42 INFO - 'LANG': 'en_US.UTF-8',
08:36:42 INFO - 'LANGUAGE': 'en_US:en',
08:36:42 INFO - 'LOGNAME': 'cltbld',
08:36:42 INFO - 'MAIL': '/var/mail/cltbld',
08:36:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:42 INFO - 'MOZ_NO_REMOTE': '1',
08:36:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:42 INFO - 'NO_EM_RESTART': '1',
08:36:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:42 INFO - 'PWD': '/builds/slave/test',
08:36:42 INFO - 'SHELL': '/bin/bash',
08:36:42 INFO - 'SHLVL': '1',
08:36:42 INFO - 'TERM': 'linux',
08:36:42 INFO - 'TMOUT': '86400',
08:36:42 INFO - 'USER': 'cltbld',
08:36:42 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:42 INFO - '_': '/tools/buildbot/bin/python'}
08:36:42 INFO - Ignoring indexes: https://pypi.python.org/simple
08:36:42 INFO - Collecting psutil>=3.1.1
08:36:43 INFO - Installing collected packages: psutil
08:36:43 INFO - Successfully installed psutil-3.1.1
08:36:43 INFO - Return code: 0
08:36:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
08:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36:43 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
08:36:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
08:36:43 INFO - Using env: {'DISPLAY': ':0',
08:36:43 INFO - 'HOME': '/home/cltbld',
08:36:43 INFO - 'LANG': 'en_US.UTF-8',
08:36:43 INFO - 'LANGUAGE': 'en_US:en',
08:36:43 INFO - 'LOGNAME': 'cltbld',
08:36:43 INFO - 'MAIL': '/var/mail/cltbld',
08:36:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:43 INFO - 'MOZ_NO_REMOTE': '1',
08:36:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:43 INFO - 'NO_EM_RESTART': '1',
08:36:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:43 INFO - 'PWD': '/builds/slave/test',
08:36:43 INFO - 'SHELL': '/bin/bash',
08:36:43 INFO - 'SHLVL': '1',
08:36:43 INFO - 'TERM': 'linux',
08:36:43 INFO - 'TMOUT': '86400',
08:36:43 INFO - 'USER': 'cltbld',
08:36:43 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:43 INFO - '_': '/tools/buildbot/bin/python'}
08:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple
08:36:44 INFO - Collecting mozsystemmonitor==0.3
08:36:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
08:36:45 INFO - Installing collected packages: mozsystemmonitor
08:36:45 INFO - Successfully installed mozsystemmonitor-0.3
08:36:45 INFO - Return code: 0
08:36:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
08:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36:45 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
08:36:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
08:36:45 INFO - Using env: {'DISPLAY': ':0',
08:36:45 INFO - 'HOME': '/home/cltbld',
08:36:45 INFO - 'LANG': 'en_US.UTF-8',
08:36:45 INFO - 'LANGUAGE': 'en_US:en',
08:36:45 INFO - 'LOGNAME': 'cltbld',
08:36:45 INFO - 'MAIL': '/var/mail/cltbld',
08:36:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:45 INFO - 'MOZ_NO_REMOTE': '1',
08:36:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:45 INFO - 'NO_EM_RESTART': '1',
08:36:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:45 INFO - 'PWD': '/builds/slave/test',
08:36:45 INFO - 'SHELL': '/bin/bash',
08:36:45 INFO - 'SHLVL': '1',
08:36:45 INFO - 'TERM': 'linux',
08:36:45 INFO - 'TMOUT': '86400',
08:36:45 INFO - 'USER': 'cltbld',
08:36:45 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:45 INFO - '_': '/tools/buildbot/bin/python'}
08:36:45 INFO - Ignoring indexes: https://pypi.python.org/simple
08:36:45 INFO - Collecting jsonschema==2.5.1
08:36:46 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
08:36:46 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
08:36:47 INFO - Installing collected packages: functools32, jsonschema
08:36:47 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
08:36:47 INFO - Return code: 0
08:36:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
08:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36:47 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
08:36:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
08:36:47 INFO - Using env: {'DISPLAY': ':0',
08:36:47 INFO - 'HOME': '/home/cltbld',
08:36:47 INFO - 'LANG': 'en_US.UTF-8',
08:36:47 INFO - 'LANGUAGE': 'en_US:en',
08:36:47 INFO - 'LOGNAME': 'cltbld',
08:36:47 INFO - 'MAIL': '/var/mail/cltbld',
08:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:47 INFO - 'MOZ_NO_REMOTE': '1',
08:36:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:47 INFO - 'NO_EM_RESTART': '1',
08:36:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:47 INFO - 'PWD': '/builds/slave/test',
08:36:47 INFO - 'SHELL': '/bin/bash',
08:36:47 INFO - 'SHLVL': '1',
08:36:47 INFO - 'TERM': 'linux',
08:36:47 INFO - 'TMOUT': '86400',
08:36:47 INFO - 'USER': 'cltbld',
08:36:47 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:47 INFO - '_': '/tools/buildbot/bin/python'}
08:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple
08:36:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
08:36:48 INFO - Return code: 0
08:36:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
08:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36:48 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
08:36:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
08:36:48 INFO - Using env: {'DISPLAY': ':0',
08:36:48 INFO - 'HOME': '/home/cltbld',
08:36:48 INFO - 'LANG': 'en_US.UTF-8',
08:36:48 INFO - 'LANGUAGE': 'en_US:en',
08:36:48 INFO - 'LOGNAME': 'cltbld',
08:36:48 INFO - 'MAIL': '/var/mail/cltbld',
08:36:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:48 INFO - 'MOZ_NO_REMOTE': '1',
08:36:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:48 INFO - 'NO_EM_RESTART': '1',
08:36:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:48 INFO - 'PWD': '/builds/slave/test',
08:36:48 INFO - 'SHELL': '/bin/bash',
08:36:48 INFO - 'SHLVL': '1',
08:36:48 INFO - 'TERM': 'linux',
08:36:48 INFO - 'TMOUT': '86400',
08:36:48 INFO - 'USER': 'cltbld',
08:36:48 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:48 INFO - '_': '/tools/buildbot/bin/python'}
08:36:48 INFO - Ignoring indexes: https://pypi.python.org/simple
08:36:48 INFO - Collecting blobuploader==1.2.4
08:36:49 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
08:36:50 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
08:36:51 INFO - Installing collected packages: requests, docopt, blobuploader
08:36:52 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
08:36:52 INFO - Return code: 0
08:36:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36:52 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
08:36:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
08:36:52 INFO - Using env: {'DISPLAY': ':0',
08:36:52 INFO - 'HOME': '/home/cltbld',
08:36:52 INFO - 'LANG': 'en_US.UTF-8',
08:36:52 INFO - 'LANGUAGE': 'en_US:en',
08:36:52 INFO - 'LOGNAME': 'cltbld',
08:36:52 INFO - 'MAIL': '/var/mail/cltbld',
08:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:52 INFO - 'MOZ_NO_REMOTE': '1',
08:36:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:52 INFO - 'NO_EM_RESTART': '1',
08:36:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:52 INFO - 'PWD': '/builds/slave/test',
08:36:52 INFO - 'SHELL': '/bin/bash',
08:36:52 INFO - 'SHLVL': '1',
08:36:52 INFO - 'TERM': 'linux',
08:36:52 INFO - 'TMOUT': '86400',
08:36:52 INFO - 'USER': 'cltbld',
08:36:52 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:52 INFO - '_': '/tools/buildbot/bin/python'}
08:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple
08:36:52 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:36:52 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:36:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:36:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:36:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:36:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:36:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:36:53 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:36:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:36:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:36:54 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:36:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:36:54 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:36:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:36:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:36:55 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:36:55 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:36:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
08:36:55 INFO - Running setup.py install for manifestparser: started
08:36:56 INFO - Running setup.py install for manifestparser: finished with status 'done'
08:36:56 INFO - Running setup.py install for mozcrash: started
08:36:56 INFO - Running setup.py install for mozcrash: finished with status 'done'
08:36:56 INFO - Running setup.py install for mozdebug: started
08:36:56 INFO - Running setup.py install for mozdebug: finished with status 'done'
08:36:56 INFO - Running setup.py install for mozdevice: started
08:36:56 INFO - Running setup.py install for mozdevice: finished with status 'done'
08:36:56 INFO - Running setup.py install for mozfile: started
08:36:56 INFO - Running setup.py install for mozfile: finished with status 'done'
08:36:56 INFO - Running setup.py install for mozhttpd: started
08:36:57 INFO - Running setup.py install for mozhttpd: finished with status 'done'
08:36:57 INFO - Running setup.py install for mozinfo: started
08:36:57 INFO - Running setup.py install for mozinfo: finished with status 'done'
08:36:57 INFO - Running setup.py install for mozInstall: started
08:36:57 INFO - Running setup.py install for mozInstall: finished with status 'done'
08:36:57 INFO - Running setup.py install for mozleak: started
08:36:57 INFO - Running setup.py install for mozleak: finished with status 'done'
08:36:57 INFO - Running setup.py install for mozlog: started
08:36:57 INFO - Running setup.py install for mozlog: finished with status 'done'
08:36:58 INFO - Running setup.py install for moznetwork: started
08:36:58 INFO - Running setup.py install for moznetwork: finished with status 'done'
08:36:58 INFO - Running setup.py install for mozprocess: started
08:36:58 INFO - Running setup.py install for mozprocess: finished with status 'done'
08:36:58 INFO - Running setup.py install for mozprofile: started
08:36:58 INFO - Running setup.py install for mozprofile: finished with status 'done'
08:36:58 INFO - Running setup.py install for mozrunner: started
08:36:58 INFO - Running setup.py install for mozrunner: finished with status 'done'
08:36:58 INFO - Running setup.py install for mozscreenshot: started
08:36:59 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
08:36:59 INFO - Running setup.py install for moztest: started
08:36:59 INFO - Running setup.py install for moztest: finished with status 'done'
08:36:59 INFO - Running setup.py install for mozversion: started
08:36:59 INFO - Running setup.py install for mozversion: finished with status 'done'
08:36:59 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
08:36:59 INFO - Return code: 0
08:36:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:36:59 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
08:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
08:36:59 INFO - Using env: {'DISPLAY': ':0',
08:36:59 INFO - 'HOME': '/home/cltbld',
08:36:59 INFO - 'LANG': 'en_US.UTF-8',
08:36:59 INFO - 'LANGUAGE': 'en_US:en',
08:36:59 INFO - 'LOGNAME': 'cltbld',
08:36:59 INFO - 'MAIL': '/var/mail/cltbld',
08:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:36:59 INFO - 'MOZ_NO_REMOTE': '1',
08:36:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:36:59 INFO - 'NO_EM_RESTART': '1',
08:36:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:36:59 INFO - 'PWD': '/builds/slave/test',
08:36:59 INFO - 'SHELL': '/bin/bash',
08:36:59 INFO - 'SHLVL': '1',
08:36:59 INFO - 'TERM': 'linux',
08:36:59 INFO - 'TMOUT': '86400',
08:36:59 INFO - 'USER': 'cltbld',
08:36:59 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:36:59 INFO - '_': '/tools/buildbot/bin/python'}
08:37:00 INFO - Ignoring indexes: https://pypi.python.org/simple
08:37:00 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
08:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
08:37:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
08:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
08:37:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
08:37:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
08:37:00 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
08:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
08:37:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
08:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
08:37:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
08:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
08:37:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
08:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
08:37:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
08:37:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
08:37:01 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
08:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
08:37:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
08:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
08:37:02 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
08:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
08:37:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
08:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
08:37:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
08:37:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
08:37:02 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
08:37:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
08:37:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
08:37:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
08:37:03 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
08:37:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
08:37:03 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
08:37:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
08:37:03 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
08:37:04 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
08:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
08:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
08:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
08:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
08:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
08:37:04 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
08:37:04 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
08:37:05 INFO - Installing collected packages: six, blessings
08:37:05 INFO - Successfully installed blessings-1.6 six-1.10.0
08:37:06 INFO - Return code: 0
08:37:06 INFO - Done creating virtualenv /builds/slave/test/build/venv.
08:37:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:37:06 INFO - Reading from file tmpfile_stdout
08:37:06 INFO - Reading from file tmpfile_stderr
08:37:06 INFO - Current package versions:
08:37:06 INFO - blessings == 1.6
08:37:06 INFO - blobuploader == 1.2.4
08:37:06 INFO - docopt == 0.6.1
08:37:06 INFO - functools32 == 3.2.3.post2
08:37:06 INFO - jsonschema == 2.5.1
08:37:06 INFO - manifestparser == 1.2
08:37:06 INFO - mozInstall == 1.14
08:37:06 INFO - mozcrash == 1.0
08:37:06 INFO - mozdebug == 0.1
08:37:06 INFO - mozdevice == 0.51
08:37:06 INFO - mozfile == 1.2
08:37:06 INFO - mozhttpd == 0.7
08:37:06 INFO - mozinfo == 0.10
08:37:06 INFO - mozleak == 0.1
08:37:06 INFO - mozlog == 3.5
08:37:06 INFO - moznetwork == 0.27
08:37:06 INFO - mozprocess == 0.25
08:37:06 INFO - mozprofile == 0.29
08:37:06 INFO - mozrunner == 6.13
08:37:06 INFO - mozscreenshot == 0.1
08:37:06 INFO - mozsystemmonitor == 0.3
08:37:06 INFO - moztest == 0.8
08:37:06 INFO - mozversion == 1.4
08:37:06 INFO - psutil == 3.1.1
08:37:06 INFO - requests == 1.2.3
08:37:06 INFO - six == 1.10.0
08:37:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv
08:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:37:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:37:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:37:06 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 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:37:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
08:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
08:37:06 INFO - Using env: {'DISPLAY': ':0',
08:37:06 INFO - 'HOME': '/home/cltbld',
08:37:06 INFO - 'LANG': 'en_US.UTF-8',
08:37:06 INFO - 'LANGUAGE': 'en_US:en',
08:37:06 INFO - 'LOGNAME': 'cltbld',
08:37:06 INFO - 'MAIL': '/var/mail/cltbld',
08:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:37:06 INFO - 'MOZ_NO_REMOTE': '1',
08:37:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:37:06 INFO - 'NO_EM_RESTART': '1',
08:37:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:37:06 INFO - 'PWD': '/builds/slave/test',
08:37:06 INFO - 'SHELL': '/bin/bash',
08:37:06 INFO - 'SHLVL': '1',
08:37:06 INFO - 'TERM': 'linux',
08:37:06 INFO - 'TMOUT': '86400',
08:37:06 INFO - 'USER': 'cltbld',
08:37:06 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:37:06 INFO - '_': '/tools/buildbot/bin/python'}
08:37:07 INFO - Ignoring indexes: https://pypi.python.org/simple
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
08:37:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
08:37:07 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
08:37:08 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:37:09 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/requests-2.18.3-py2.py3-none-any.whl (88kB)
08:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
08:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
08:37:09 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
08:37:09 INFO - Collecting chardet<3.1.0,>=3.0.2 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:37:10 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/chardet-3.0.4-py2.py3-none-any.whl (133kB)
08:37:10 INFO - Collecting urllib3<1.23,>=1.21.1 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:37:11 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/urllib3-1.22-py2.py3-none-any.whl (132kB)
08:37:11 INFO - Collecting certifi>=2017.4.17 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:37:12 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/certifi-2017.7.27.1-py2.py3-none-any.whl (349kB)
08:37:12 INFO - Collecting idna<2.6,>=2.5 (from requests>=2.9.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
08:37:13 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/idna-2.5-py2.py3-none-any.whl (55kB)
08:37:13 INFO - Installing collected packages: simplejson, chardet, urllib3, certifi, idna, requests
08:37:13 INFO - Found existing installation: requests 1.2.3
08:37:13 INFO - Uninstalling requests-1.2.3:
08:37:13 INFO - Successfully uninstalled requests-1.2.3
08:37:13 INFO - Successfully installed certifi-2017.7.27.1 chardet-3.0.4 idna-2.5 requests-2.18.3 simplejson-3.3.0 urllib3-1.22
08:37:13 INFO - Return code: 0
08:37:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
08:37: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')]}
08:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
08:37: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')]}
08:37:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
08:37: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29912d0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b69a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2d0cbc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, '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': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
08:37: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'] in /builds/slave/test/build
08:37: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
08:37:13 INFO - Using env: {'DISPLAY': ':0',
08:37:13 INFO - 'HOME': '/home/cltbld',
08:37:13 INFO - 'LANG': 'en_US.UTF-8',
08:37:13 INFO - 'LANGUAGE': 'en_US:en',
08:37:13 INFO - 'LOGNAME': 'cltbld',
08:37:13 INFO - 'MAIL': '/var/mail/cltbld',
08:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:37:13 INFO - 'MOZ_NO_REMOTE': '1',
08:37:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:37:13 INFO - 'NO_EM_RESTART': '1',
08:37:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:37:13 INFO - 'PWD': '/builds/slave/test',
08:37:13 INFO - 'SHELL': '/bin/bash',
08:37:13 INFO - 'SHLVL': '1',
08:37:13 INFO - 'TERM': 'linux',
08:37:13 INFO - 'TMOUT': '86400',
08:37:13 INFO - 'USER': 'cltbld',
08:37:13 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:37:13 INFO - '_': '/tools/buildbot/bin/python'}
08:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple
08:37:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
08:37:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
08:37:14 INFO - Return code: 0
08:37:14 INFO - Running post-action listener: _resource_record_post_action
08:37:14 INFO - Running post-action listener: _start_resource_monitoring
08:37:14 INFO - Starting resource monitoring.
08:37:14 INFO - [mozharness: 2017-10-31 15:37:14.484458Z] Finished create-virtualenv step (success)
08:37:14 INFO - [mozharness: 2017-10-31 15:37:14.484924Z] Running install step.
08:37:14 INFO - Running pre-action listener: _resource_record_pre_action
08:37:14 INFO - Running main action method: install
08:37:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
08:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
08:37:14 INFO - Reading from file tmpfile_stdout
08:37:14 INFO - Reading from file tmpfile_stderr
08:37:14 INFO - Detecting whether we're running mozinstall >=1.0...
08:37:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
08:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
08:37:15 INFO - Reading from file tmpfile_stdout
08:37:15 INFO - Output received:
08:37:15 INFO - Usage: mozinstall [options] installer
08:37:15 INFO - Options:
08:37:15 INFO - -h, --help show this help message and exit
08:37:15 INFO - -d DEST, --destination=DEST
08:37:15 INFO - Directory to install application into. [default:
08:37:15 INFO - "/builds/slave/test"]
08:37:15 INFO - --app=APP Application being installed. [default: firefox]
08:37:15 INFO - mkdir: /builds/slave/test/build/application
08:37:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
08:37:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
08:37:33 INFO - Reading from file tmpfile_stdout
08:37:33 INFO - Output received:
08:37:33 INFO - /builds/slave/test/build/application/firefox/firefox
08:37:33 INFO - Running post-action listener: _resource_record_post_action
08:37:33 INFO - [mozharness: 2017-10-31 15:37:33.453131Z] Finished install step (success)
08:37:33 INFO - [mozharness: 2017-10-31 15:37:33.453274Z] Running setup-mitmproxy step.
08:37:33 INFO - Running pre-action listener: _resource_record_pre_action
08:37:33 INFO - Running main action method: setup_mitmproxy
08:37:33 INFO - Skipping: mitmproxy is not required
08:37:33 INFO - Running post-action listener: _resource_record_post_action
08:37:33 INFO - [mozharness: 2017-10-31 15:37:33.453790Z] Finished setup-mitmproxy step (success)
08:37:33 INFO - [mozharness: 2017-10-31 15:37:33.453891Z] Running run-tests step.
08:37:33 INFO - Running pre-action listener: _resource_record_pre_action
08:37:33 INFO - Running pre-action listener: _set_gcov_prefix
08:37:33 INFO - Running main action method: run_tests
08:37:33 WARNING - Try message not found.
08:37:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
08:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
08:37:33 INFO - Python 2.7.3
08:37:33 INFO - Return code: 0
08:37:33 INFO - grabbing minidump binary from tooltool
08:37:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
08:37:33 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 0x2d09da0>, '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 0x2d0aca0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2d0b130>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
08:37:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
08:37:33 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
08:37:33 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
08:37:33 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
08:37:33 INFO - Return code: 0
08:37:33 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
08:37:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
08:37:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
08:37:33 INFO - ENV: RUST_BACKTRACE is now full
08:37:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
08:37:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
08:37:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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
08:37:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite g4-stylo-disabled-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-081 --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
08:37:33 INFO - Using env: {'DISPLAY': ':0',
08:37:33 INFO - 'HOME': '/home/cltbld',
08:37:33 INFO - 'LANG': 'en_US.UTF-8',
08:37:33 INFO - 'LANGUAGE': 'en_US:en',
08:37:33 INFO - 'LOGNAME': 'cltbld',
08:37:33 INFO - 'MAIL': '/var/mail/cltbld',
08:37:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:37:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:37:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:37:33 INFO - 'MOZ_NO_REMOTE': '1',
08:37:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:37:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:37:33 INFO - 'NO_EM_RESTART': '1',
08:37:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:37:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:37:33 INFO - 'PWD': '/builds/slave/test',
08:37:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:37:33 INFO - 'RUST_BACKTRACE': 'full',
08:37:33 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:37:33 INFO - 'SHELL': '/bin/bash',
08:37:33 INFO - 'SHLVL': '1',
08:37:33 INFO - 'TERM': 'linux',
08:37:33 INFO - 'TMOUT': '86400',
08:37:33 INFO - 'USER': 'cltbld',
08:37:33 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:37:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:37:33 INFO - '_': '/tools/buildbot/bin/python'}
08:37:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'g4-stylo-disabled-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-081', '--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
08:37:33 INFO - mozversion application_buildid: 20171031144333
08:37:33 INFO - mozversion application_changeset: 0a7ff6e19bcc229500d92597fac9340d9bdef959
08:37:33 INFO - mozversion application_display_name: Nightly
08:37:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
08:37:33 INFO - mozversion application_name: Firefox
08:37:33 INFO - mozversion application_remotingname: firefox
08:37:33 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
08:37:33 INFO - mozversion application_vendor: Mozilla
08:37:33 INFO - mozversion application_version: 58.0a1
08:37:33 INFO - mozversion platform_buildid: 20171031144333
08:37:33 INFO - mozversion platform_changeset: 0a7ff6e19bcc229500d92597fac9340d9bdef959
08:37:33 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
08:37:33 INFO - mozversion platform_version: 58.0a1
08:37:33 INFO - using testdate: 1509464253
08:37:33 INFO - actual date: 1509464253
08:37:33 INFO - starting webserver on 'localhost:47028'
08:37:33 INFO - SUITE-START | Running 2 tests
08:37:33 INFO - TEST-START | basic_compositor_video
08:37:33 INFO - Initialising browser for basic_compositor_video test...
08:37:33 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47028/getInfo.html -profile /tmp/tmpfwWDep/profile
08:37:33 INFO - TEST-INFO | started process 19875 (/builds/slave/test/build/application/firefox/firefox http://localhost:47028/getInfo.html)
08:37:38 INFO - TEST-INFO | 19875: exit 0
08:37:38 INFO - Browser initialized.
08:37:38 INFO - Running cycle 1/1 for basic_compositor_video test...
08:37:38 INFO - Using env: {'DISPLAY': ':0',
08:37:38 INFO - 'HOME': '/home/cltbld',
08:37:38 INFO - 'JSGC_DISABLE_POISONING': '1',
08:37:38 INFO - 'LANG': 'en_US.UTF-8',
08:37:38 INFO - 'LANGUAGE': 'en_US:en',
08:37:38 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:37:38 INFO - 'LOGNAME': 'cltbld',
08:37:38 INFO - 'MAIL': '/var/mail/cltbld',
08:37:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:37:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:37:38 INFO - 'MOZ_CRASHREPORTER': '1',
08:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:37:38 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:37:38 INFO - 'MOZ_NO_REMOTE': '1',
08:37:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:37:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:37:38 INFO - 'NO_EM_RESTART': '1',
08:37:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:37:38 INFO - 'PWD': '/builds/slave/test',
08:37:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:37:38 INFO - 'RUST_BACKTRACE': 'full',
08:37:38 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:37:38 INFO - 'SHELL': '/bin/bash',
08:37:38 INFO - 'SHLVL': '1',
08:37:38 INFO - 'STYLO_FORCE_DISABLED': '1',
08:37:38 INFO - 'TERM': 'linux',
08:37:38 INFO - 'TMOUT': '86400',
08:37:38 INFO - 'USER': 'cltbld',
08:37:38 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:37:38 INFO - '_': '/tools/buildbot/bin/python'}
08:37:38 INFO - TEST-INFO | started process 20143 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpfwWDep/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
08:37:39 INFO - PID 20143 |
08:37:39 INFO - PID 20143 | (/builds/slave/test/build/application/firefox/firefox:20194): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:37:39 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6885143500281374 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682944120100085 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7383632676709153 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7289757994814168 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 3.974754966887418 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.185794979079497 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.6063221153846143 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.565909090909095 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.803897485493226 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.52653314917127 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.74181818181818 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.714150485436884 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.538423076923083 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.111472222222234 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450892116182576 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.839852071005946 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.722050781249976 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.052569060773518 ms/frame
08:38:25 INFO - PID 20143 |
08:38:25 INFO - PID 20143 | Cycle 1(1): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:39:11 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6879527559055116 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.667041666666667 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7579935559461046 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7749201419698306 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9793236074270557 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.8473750000000004 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.5549526066350703 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.430771869639793 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.860888671875003 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.573091922005565 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.52693650793651 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.617283653846156 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.586872586872587 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.052624309392273 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.400268595041345 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.905446428571416 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49469348659003 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.054834254143662 ms/frame
08:39:11 INFO - PID 20143 |
08:39:11 INFO - PID 20143 | Cycle 1(2): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:39:58 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.689428490990991 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.67004590984975 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7702064896755163 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7382971329278893 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.238185028248585 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.230613107822414 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.632627118644069 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.6314972776769476 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.703878326996194 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.377096774193555 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.870904605263162 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.571100478468903 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.237022471910112 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.640930851063805 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450373443983406 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907440476190501 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.495785440613032 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.115444444444439 ms/frame
08:39:58 INFO - PID 20143 |
08:39:58 INFO - PID 20143 | Cycle 1(3): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:40:44 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838411896745231 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6667666666666663 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7827748068924545 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7621718061674014 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.049730094466937 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.008226452905811 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.649738442822388 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5221214788732373 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.814244186046515 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.649999999999996 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.585431309904147 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.348200934579436 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.326811320754702 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.7540053763441 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.55489539748952 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90604166666669 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.585888030888041 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.174357541899436 ms/frame
08:40:44 INFO - PID 20143 |
08:40:44 INFO - PID 20143 | Cycle 1(4): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:41:30 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6790794627867938 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6703505843071789 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8170896426408238 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7523117338003507 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.214550561797753 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9226666666666694 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.6688080684596587 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.527980599647263 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.826611650485433 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5889525139664835 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.679064516129028 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.808455882352938 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.321471698113216 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.930846994535525 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.66139240506329 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.054367469879484 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49530651340994 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932349726775971 ms/frame
08:41:30 INFO - PID 20143 |
08:41:30 INFO - PID 20143 | Cycle 1(5): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:42:16 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.681992713004484 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6702003338898164 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.811639492753623 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7718866253321532 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.178614206128132 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9620990099009914 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.5424498229043704 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4253253424657526 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.704743346007604 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.636239436619722 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.838147540983613 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.349392523364488 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.453396946564874 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.111916666666689 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.450269709543566 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.835355029585816 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.365928030303042 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.933770491803264 ms/frame
08:42:16 INFO - PID 20143 |
08:42:16 INFO - PID 20143 | Cycle 1(6): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:42:39 INFO - PID 20143 |
08:42:39 INFO - PID 20143 | (/builds/slave/test/build/application/firefox/firefox:20274): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:42:39 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6827762198541782 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.66735 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7872483621203097 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.789163685152058 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.167229166666668 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.9928942115768438 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.5890968899521516 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.478660869565217 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.725610687022903 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.333826666666661 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 10.274965753424661 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.390985915492953 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.4512786259542 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.87413043478259 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.50427083333334 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.700906432748514 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.497011494252877 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.87529891304348 ms/frame
08:43:02 INFO - PID 20143 |
08:43:02 INFO - PID 20143 | Cycle 1(7): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:43:48 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6888435565559934 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6726379598662207 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7954278874925198 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7882841823056301 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.20788920056101 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.175501043841337 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.61913148371532 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5530195381882743 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.682263257575752 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.556208333333335 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.68003225806452 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.664589371980675 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.323283018867931 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.931693989071038 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.552782426778235 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.12212121212122 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.49766283524904 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.055718232044194 ms/frame
08:43:48 INFO - PID 20143 |
08:43:48 INFO - PID 20143 | Cycle 1(8): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:44:35 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.68179932735426 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6687406171809842 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7713046044864227 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.75020997375328 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.179345403899722 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.221613924050633 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.4967191142191134 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4316380789022305 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.84897660818713 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.666189801699719 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.348660436137077 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.902970297029691 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.45333969465648 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.054116022099478 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.607941176470568 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.98191616766469 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.498333333333342 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.930464480874324 ms/frame
08:44:35 INFO - PID 20143 |
08:44:35 INFO - PID 20143 | Cycle 1(9): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:45:21 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6831912507010653 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6700709515859768 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7829768270944748 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7412837249782416 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.209053295932679 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.158617463617464 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.7132363861386115 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5729910714285715 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.672344045368623 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.683352272727276 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.773550488599348 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 8.851526548672565 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.455572519083972 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.992060439560442 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503020833333327 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.125636363636339 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.323584905660377 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.754704301075268 ms/frame
08:45:21 INFO - PID 20143 |
08:45:21 INFO - PID 20143 | Cycle 1(10): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:46:07 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6894453828828828 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686155129274396 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7944796650717703 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7658428949691092 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.071112618724562 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.194989517819703 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.5469267139479914 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4733420138888897 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.6611603773584855 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.700056980056983 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.012147147147141 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 9.854901477832522 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.49655172413794 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.990082417582416 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.712351694915277 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050933734939726 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.364924242424228 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.113916666666693 ms/frame
08:46:07 INFO - PID 20143 |
08:46:07 INFO - PID 20143 | Cycle 1(11): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:46:53 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_startup = 1.682017937219731 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6676749999999996 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7710832349468715 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7703761061946899 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_startup = 4.286821428571428 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.149813278008297 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_startup = 3.497039627039626 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1_inclip = 3.46641247833622 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.780539499036606 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.572270194986071 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_startup = 9.836360655737705 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.480p.60fps.webm_scale_2_inclip = 10.20834183673469 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.674941634241241 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.053093922651938 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.503208333333351 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.977874251497008 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.674610894941635 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.173240223463699 ms/frame
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | Cycle 1(12): loaded http://localhost:47028/tests/video/video_playback.html (next: http://localhost:47028/tests/video/video_playback.html)
08:46:53 INFO - PID 20143 | __start_tp_report
08:46:53 INFO - PID 20143 | _x_x_mozilla_page_load
08:46:53 INFO - PID 20143 | _x_x_mozilla_page_load_details
08:46:53 INFO - PID 20143 | |i|pagename|runs|
08:46:53 INFO - PID 20143 | |0;240p.120fps.mp4_scale_1_startup;1.6885143500281374;1.6879527559055116;1.689428490990991;1.6838411896745231;1.6790794627867938;1.681992713004484;1.6827762198541782;1.6888435565559934;1.68179932735426;1.6831912507010653;1.6894453828828828;1.682017937219731
08:46:53 INFO - PID 20143 | |1;240p.120fps.mp4_scale_1_inclip;1.6682944120100085;1.667041666666667;1.67004590984975;1.6667666666666663;1.6703505843071789;1.6702003338898164;1.66735;1.6726379598662207;1.6687406171809842;1.6700709515859768;1.6686155129274396;1.6676749999999996
08:46:53 INFO - PID 20143 | |2;240p.120fps.mp4_scale_1.1_startup;1.7383632676709153;1.7579935559461046;1.7702064896755163;1.7827748068924545;1.8170896426408238;1.811639492753623;1.7872483621203097;1.7954278874925198;1.7713046044864227;1.7829768270944748;1.7944796650717703;1.7710832349468715
08:46:53 INFO - PID 20143 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7289757994814168;1.7749201419698306;1.7382971329278893;1.7621718061674014;1.7523117338003507;1.7718866253321532;1.789163685152058;1.7882841823056301;1.75020997375328;1.7412837249782416;1.7658428949691092;1.7703761061946899
08:46:53 INFO - PID 20143 | |4;240p.120fps.mp4_scale_2_startup;3.974754966887418;3.9793236074270557;4.238185028248585;4.049730094466937;4.214550561797753;4.178614206128132;4.167229166666668;4.20788920056101;4.179345403899722;4.209053295932679;4.071112618724562;4.286821428571428
08:46:53 INFO - PID 20143 | |5;240p.120fps.mp4_scale_2_inclip;4.185794979079497;3.8473750000000004;4.230613107822414;4.008226452905811;3.9226666666666694;3.9620990099009914;3.9928942115768438;4.175501043841337;4.221613924050633;4.158617463617464;4.194989517819703;4.149813278008297
08:46:53 INFO - PID 20143 | |6;480p.60fps.webm_scale_1_startup;3.6063221153846143;3.5549526066350703;3.632627118644069;3.649738442822388;3.6688080684596587;3.5424498229043704;3.5890968899521516;3.61913148371532;3.4967191142191134;3.7132363861386115;3.5469267139479914;3.497039627039626
08:46:53 INFO - PID 20143 | |7;480p.60fps.webm_scale_1_inclip;3.565909090909095;3.430771869639793;3.6314972776769476;3.5221214788732373;3.527980599647263;3.4253253424657526;3.478660869565217;3.5530195381882743;3.4316380789022305;3.5729910714285715;3.4733420138888897;3.46641247833622
08:46:53 INFO - PID 20143 | |8;480p.60fps.webm_scale_1.1_startup;5.803897485493226;5.860888671875003;5.703878326996194;5.814244186046515;5.826611650485433;5.704743346007604;5.725610687022903;5.682263257575752;5.84897660818713;5.672344045368623;5.6611603773584855;5.780539499036606
08:46:53 INFO - PID 20143 | |9;480p.60fps.webm_scale_1.1_inclip;5.52653314917127;5.573091922005565;5.377096774193555;5.649999999999996;5.5889525139664835;5.636239436619722;5.333826666666661;5.556208333333335;5.666189801699719;5.683352272727276;5.700056980056983;5.572270194986071
08:46:53 INFO - PID 20143 | |10;480p.60fps.webm_scale_2_startup;9.74181818181818;9.52693650793651;9.870904605263162;9.585431309904147;9.679064516129028;9.838147540983613;10.274965753424661;9.68003225806452;9.348660436137077;9.773550488599348;9.012147147147141;9.836360655737705
08:46:53 INFO - PID 20143 | |11;480p.60fps.webm_scale_2_inclip;9.714150485436884;9.617283653846156;9.571100478468903;9.348200934579436;9.808455882352938;9.349392523364488;9.390985915492953;9.664589371980675;9.902970297029691;8.851526548672565;9.854901477832522;10.20834183673469
08:46:53 INFO - PID 20143 | |12;1080p.60fps.mp4_scale_1_startup;11.538423076923083;11.586872586872587;11.237022471910112;11.326811320754702;11.321471698113216;11.453396946564874;11.4512786259542;11.323283018867931;11.45333969465648;11.455572519083972;11.49655172413794;11.674941634241241
08:46:53 INFO - PID 20143 | |13;1080p.60fps.mp4_scale_1_inclip;11.111472222222234;11.052624309392273;10.640930851063805;10.7540053763441;10.930846994535525;11.111916666666689;10.87413043478259;10.931693989071038;11.054116022099478;10.992060439560442;10.990082417582416;11.053093922651938
08:46:53 INFO - PID 20143 | |14;1080p.60fps.mp4_scale_1.1_startup;12.450892116182576;12.400268595041345;12.450373443983406;12.55489539748952;12.66139240506329;12.450269709543566;12.50427083333334;12.552782426778235;12.607941176470568;12.503020833333327;12.712351694915277;12.503208333333351
08:46:53 INFO - PID 20143 | |15;1080p.60fps.mp4_scale_1.1_inclip;11.839852071005946;11.905446428571416;11.907440476190501;11.90604166666669;12.054367469879484;11.835355029585816;11.700906432748514;12.12212121212122;11.98191616766469;12.125636363636339;12.050933734939726;11.977874251497008
08:46:53 INFO - PID 20143 | |16;1080p.60fps.mp4_scale_2_startup;11.722050781249976;11.49469348659003;11.495785440613032;11.585888030888041;11.49530651340994;11.365928030303042;11.497011494252877;11.49766283524904;11.498333333333342;11.323584905660377;11.364924242424228;11.674610894941635
08:46:53 INFO - PID 20143 | |17;1080p.60fps.mp4_scale_2_inclip;11.052569060773518;11.054834254143662;11.115444444444439;11.174357541899436;10.932349726775971;10.933770491803264;10.87529891304348;11.055718232044194;10.930464480874324;10.754704301075268;11.113916666666693;11.173240223463699
08:46:53 INFO - PID 20143 | __end_tp_report
08:46:53 INFO - PID 20143 | __start_cc_report
08:46:53 INFO - PID 20143 | _x_x_mozilla_cycle_collect,622
08:46:53 INFO - PID 20143 | __end_cc_report
08:46:53 INFO - PID 20143 | __startTimestamp1509464813966__endTimestamp
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | ------- Summary: start -------
08:46:53 INFO - PID 20143 | Number of tests: 18
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.69 stddev:0.00 (0.2%) stddev-sans-first:0.00
08:46:53 INFO - PID 20143 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.1%) stddev-sans-first:0.00
08:46:53 INFO - PID 20143 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.78 Median:1.79 stddev:0.02 (1.2%) stddev-sans-first:0.02
08:46:53 INFO - PID 20143 | Values: 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.76 Median:1.77 stddev:0.02 (1.1%) stddev-sans-first:0.02
08:46:53 INFO - PID 20143 | Values: 1.7 1.8 1.7 1.8 1.8 1.8 1.8 1.8 1.8 1.7 1.8 1.8
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.15 Median:4.19 stddev:0.10 (2.4%) stddev-sans-first:0.09
08:46:53 INFO - PID 20143 | Values: 4.0 4.0 4.2 4.0 4.2 4.2 4.2 4.2 4.2 4.2 4.1 4.3
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.09 Median:4.17 stddev:0.13 (3.2%) stddev-sans-first:0.13
08:46:53 INFO - PID 20143 | Values: 4.2 3.8 4.2 4.0 3.9 4.0 4.0 4.2 4.2 4.2 4.2 4.1
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.59 Median:3.61 stddev:0.07 (1.9%) stddev-sans-first:0.07
08:46:53 INFO - PID 20143 | Values: 3.6 3.6 3.6 3.6 3.7 3.5 3.6 3.6 3.5 3.7 3.5 3.5
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.51 Median:3.53 stddev:0.07 (1.9%) stddev-sans-first:0.07
08:46:53 INFO - PID 20143 | Values: 3.6 3.4 3.6 3.5 3.5 3.4 3.5 3.6 3.4 3.6 3.5 3.5
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.76 Median:5.79 stddev:0.07 (1.3%) stddev-sans-first:0.07
08:46:53 INFO - PID 20143 | Values: 5.8 5.9 5.7 5.8 5.8 5.7 5.7 5.7 5.8 5.7 5.7 5.8
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.57 Median:5.61 stddev:0.11 (2.0%) stddev-sans-first:0.12
08:46:53 INFO - PID 20143 | Values: 5.5 5.6 5.4 5.6 5.6 5.6 5.3 5.6 5.7 5.7 5.7 5.6
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.68 Median:9.71 stddev:0.31 (3.2%) stddev-sans-first:0.32
08:46:53 INFO - PID 20143 | Values: 9.7 9.5 9.9 9.6 9.7 9.8 10.3 9.7 9.3 9.8 9.0 9.8
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.61 Median:9.64 stddev:0.35 (3.6%) stddev-sans-first:0.36
08:46:53 INFO - PID 20143 | Values: 9.7 9.6 9.6 9.3 9.8 9.3 9.4 9.7 9.9 8.9 9.9 10.2
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.44 Median:11.45 stddev:0.13 (1.1%) stddev-sans-first:0.13
08:46:53 INFO - PID 20143 | Values: 11.5 11.6 11.2 11.3 11.3 11.5 11.5 11.3 11.5 11.5 11.5 11.7
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.96 Median:11.02 stddev:0.14 (1.3%) stddev-sans-first:0.14
08:46:53 INFO - PID 20143 | Values: 11.1 11.1 10.6 10.8 10.9 11.1 10.9 10.9 11.1 11.0 11.0 11.1
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.53 Median:12.53 stddev:0.09 (0.7%) stddev-sans-first:0.09
08:46:53 INFO - PID 20143 | Values: 12.5 12.4 12.5 12.6 12.7 12.5 12.5 12.6 12.6 12.5 12.7 12.5
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.95 Median:11.98 stddev:0.13 (1.1%) stddev-sans-first:0.13
08:46:53 INFO - PID 20143 | Values: 11.8 11.9 11.9 11.9 12.1 11.8 11.7 12.1 12.0 12.1 12.1 12.0
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.50 Median:11.50 stddev:0.12 (1.0%) stddev-sans-first:0.10
08:46:53 INFO - PID 20143 | Values: 11.7 11.5 11.5 11.6 11.5 11.4 11.5 11.5 11.5 11.3 11.4 11.7
08:46:53 INFO - PID 20143 |
08:46:53 INFO - PID 20143 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:11.01 Median:11.06 stddev:0.13 (1.2%) stddev-sans-first:0.13
08:46:53 INFO - PID 20143 | Values: 11.1 11.1 11.1 11.2 10.9 10.9 10.9 11.1 10.9 10.8 11.1 11.2
08:46:53 INFO - PID 20143 | -------- Summary: end --------
08:46:53 INFO - PID 20143 |
08:46:54 INFO - TEST-INFO | 20143: exit 0
08:46:54 INFO - TEST-OK | basic_compositor_video | took 560864ms
08:46:54 INFO - TEST-START | glvideo
08:46:54 INFO - Initialising browser for glvideo test...
08:46:54 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:47028/getInfo.html -profile /tmp/tmp4zdvEq/profile
08:46:54 INFO - TEST-INFO | started process 21431 (/builds/slave/test/build/application/firefox/firefox http://localhost:47028/getInfo.html)
08:46:59 INFO - TEST-INFO | 21431: exit 0
08:46:59 INFO - Browser initialized.
08:46:59 INFO - Running cycle 1/1 for glvideo test...
08:46:59 INFO - Using env: {'DISPLAY': ':0',
08:46:59 INFO - 'HOME': '/home/cltbld',
08:46:59 INFO - 'JSGC_DISABLE_POISONING': '1',
08:46:59 INFO - 'LANG': 'en_US.UTF-8',
08:46:59 INFO - 'LANGUAGE': 'en_US:en',
08:46:59 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
08:46:59 INFO - 'LOGNAME': 'cltbld',
08:46:59 INFO - 'MAIL': '/var/mail/cltbld',
08:46:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
08:46:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
08:46:59 INFO - 'MOZ_CRASHREPORTER': '1',
08:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
08:46:59 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
08:46:59 INFO - 'MOZ_NO_REMOTE': '1',
08:46:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
08:46:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
08:46:59 INFO - 'NO_EM_RESTART': '1',
08:46:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
08:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
08:46:59 INFO - 'PWD': '/builds/slave/test',
08:46:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
08:46:59 INFO - 'RUST_BACKTRACE': 'full',
08:46:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
08:46:59 INFO - 'SHELL': '/bin/bash',
08:46:59 INFO - 'SHLVL': '1',
08:46:59 INFO - 'STYLO_FORCE_DISABLED': '1',
08:46:59 INFO - 'TERM': 'linux',
08:46:59 INFO - 'TMOUT': '86400',
08:46:59 INFO - 'USER': 'cltbld',
08:46:59 INFO - 'XDG_SESSION_COOKIE': '2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067',
08:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
08:46:59 INFO - '_': '/tools/buildbot/bin/python'}
08:46:59 INFO - TEST-INFO | started process 21695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4zdvEq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
08:47:00 INFO - PID 21695 |
08:47:00 INFO - PID 21695 | (/builds/slave/test/build/application/firefox/firefox:21746): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
08:47:00 INFO - PID 21695 |
08:47:02 INFO - PID 21695 | [talos glvideo result] Mean tick time across 100 ticks: 9.9662 ms
08:47:02 INFO - PID 21695 | Cycle 1(1): loaded http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html)
08:47:03 INFO - PID 21695 | [talos glvideo result] Mean tick time across 100 ticks: 6.441949999999999 ms
08:47:03 INFO - PID 21695 | Cycle 1(2): loaded http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html)
08:47:04 INFO - PID 21695 | [talos glvideo result] Mean tick time across 100 ticks: 6.525949999999999 ms
08:47:04 INFO - PID 21695 | Cycle 1(3): loaded http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html)
08:47:06 INFO - PID 21695 | [talos glvideo result] Mean tick time across 100 ticks: 6.56785 ms
08:47:06 INFO - PID 21695 | Cycle 1(4): loaded http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html)
08:47:07 INFO - PID 21695 | [talos glvideo result] Mean tick time across 100 ticks: 6.675549999999999 ms
08:47:07 INFO - PID 21695 | Cycle 1(5): loaded http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:47028/tests/webgl/benchmarks/video/video_upload.html)
08:47:07 INFO - PID 21695 | __start_tp_report
08:47:07 INFO - PID 21695 | _x_x_mozilla_page_load
08:47:07 INFO - PID 21695 | _x_x_mozilla_page_load_details
08:47:07 INFO - PID 21695 | |i|pagename|runs|
08:47:07 INFO - PID 21695 | |0;Mean tick time across 100 ticks: ;9.9662;6.441949999999999;6.525949999999999;6.56785;6.675549999999999
08:47:07 INFO - PID 21695 | __end_tp_report
08:47:07 INFO - PID 21695 | __start_cc_report
08:47:07 INFO - PID 21695 | _x_x_mozilla_cycle_collect,292
08:47:07 INFO - PID 21695 | __end_cc_report
08:47:07 INFO - PID 21695 | __startTimestamp1509464827719__endTimestamp
08:47:07 INFO - PID 21695 |
08:47:07 INFO - PID 21695 | ------- Summary: start -------
08:47:07 INFO - PID 21695 | Number of tests: 1
08:47:07 INFO - PID 21695 |
08:47:07 INFO - PID 21695 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:7.24 Median:6.57 stddev:1.53 (23.3%) stddev-sans-first:0.10
08:47:07 INFO - PID 21695 | Values: 10.0 6.4 6.5 6.6 6.7
08:47:07 INFO - PID 21695 | -------- Summary: end --------
08:47:07 INFO - PID 21695 |
08:47:08 INFO - PID 21695 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
08:47:08 INFO - TEST-INFO | 21695: exit 0
08:47:08 INFO - TEST-OK | glvideo | took 13741ms
08:47:08 INFO - SUITE-END | took 574s
08:47:08 INFO - Completed test suite (00:09:34)
08:47:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.621384421901335, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6885143500281374, 1.6879527559055116, 1.689428490990991, 1.6838411896745231, 1.6790794627867938, 1.681992713004484, 1.6827762198541782, 1.6888435565559934, 1.68179932735426, 1.6831912507010653, 1.6894453828828828, 1.682017937219731], "value": 1.6831912507010653, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6682944120100085, 1.667041666666667, 1.67004590984975, 1.6667666666666663, 1.6703505843071789, 1.6702003338898164, 1.66735, 1.6726379598662207, 1.6687406171809842, 1.6700709515859768, 1.6686155129274396, 1.6676749999999996], "value": 1.6687406171809842, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7383632676709153, 1.7579935559461046, 1.7702064896755163, 1.7827748068924545, 1.8170896426408238, 1.811639492753623, 1.7872483621203097, 1.7954278874925198, 1.7713046044864227, 1.7829768270944748, 1.7944796650717703, 1.7710832349468715], "value": 1.7829768270944748, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7289757994814168, 1.7749201419698306, 1.7382971329278893, 1.7621718061674014, 1.7523117338003507, 1.7718866253321532, 1.789163685152058, 1.7882841823056301, 1.75020997375328, 1.7412837249782416, 1.7658428949691092, 1.7703761061946899], "value": 1.7658428949691092, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.974754966887418, 3.9793236074270557, 4.238185028248585, 4.049730094466937, 4.214550561797753, 4.178614206128132, 4.167229166666668, 4.20788920056101, 4.179345403899722, 4.209053295932679, 4.071112618724562, 4.286821428571428], "value": 4.179345403899722, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.185794979079497, 3.8473750000000004, 4.230613107822414, 4.008226452905811, 3.9226666666666694, 3.9620990099009914, 3.9928942115768438, 4.175501043841337, 4.221613924050633, 4.158617463617464, 4.194989517819703, 4.149813278008297], "value": 4.149813278008297, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.6063221153846143, 3.5549526066350703, 3.632627118644069, 3.649738442822388, 3.6688080684596587, 3.5424498229043704, 3.5890968899521516, 3.61913148371532, 3.4967191142191134, 3.7132363861386115, 3.5469267139479914, 3.497039627039626], "value": 3.5890968899521516, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.565909090909095, 3.430771869639793, 3.6314972776769476, 3.5221214788732373, 3.527980599647263, 3.4253253424657526, 3.478660869565217, 3.5530195381882743, 3.4316380789022305, 3.5729910714285715, 3.4733420138888897, 3.46641247833622], "value": 3.478660869565217, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.803897485493226, 5.860888671875003, 5.703878326996194, 5.814244186046515, 5.826611650485433, 5.704743346007604, 5.725610687022903, 5.682263257575752, 5.84897660818713, 5.672344045368623, 5.6611603773584855, 5.780539499036606], "value": 5.725610687022903, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.52653314917127, 5.573091922005565, 5.377096774193555, 5.649999999999996, 5.5889525139664835, 5.636239436619722, 5.333826666666661, 5.556208333333335, 5.666189801699719, 5.683352272727276, 5.700056980056983, 5.572270194986071], "value": 5.5889525139664835, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.74181818181818, 9.52693650793651, 9.870904605263162, 9.585431309904147, 9.679064516129028, 9.838147540983613, 10.274965753424661, 9.68003225806452, 9.348660436137077, 9.773550488599348, 9.012147147147141, 9.836360655737705], "value": 9.68003225806452, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.714150485436884, 9.617283653846156, 9.571100478468903, 9.348200934579436, 9.808455882352938, 9.349392523364488, 9.390985915492953, 9.664589371980675, 9.902970297029691, 8.851526548672565, 9.854901477832522, 10.20834183673469], "value": 9.617283653846156, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.538423076923083, 11.586872586872587, 11.237022471910112, 11.326811320754702, 11.321471698113216, 11.453396946564874, 11.4512786259542, 11.323283018867931, 11.45333969465648, 11.455572519083972, 11.49655172413794, 11.674941634241241], "value": 11.45333969465648, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.111472222222234, 11.052624309392273, 10.640930851063805, 10.7540053763441, 10.930846994535525, 11.111916666666689, 10.87413043478259, 10.931693989071038, 11.054116022099478, 10.992060439560442, 10.990082417582416, 11.053093922651938], "value": 10.990082417582416, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.450892116182576, 12.400268595041345, 12.450373443983406, 12.55489539748952, 12.66139240506329, 12.450269709543566, 12.50427083333334, 12.552782426778235, 12.607941176470568, 12.503020833333327, 12.712351694915277, 12.503208333333351], "value": 12.50427083333334, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.839852071005946, 11.905446428571416, 11.907440476190501, 11.90604166666669, 12.054367469879484, 11.835355029585816, 11.700906432748514, 12.12212121212122, 11.98191616766469, 12.125636363636339, 12.050933734939726, 11.977874251497008], "value": 11.977874251497008, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.722050781249976, 11.49469348659003, 11.495785440613032, 11.585888030888041, 11.49530651340994, 11.365928030303042, 11.497011494252877, 11.49766283524904, 11.498333333333342, 11.323584905660377, 11.364924242424228, 11.674610894941635], "value": 11.495785440613032, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.052569060773518, 11.054834254143662, 11.115444444444439, 11.174357541899436, 10.932349726775971, 10.933770491803264, 10.87529891304348, 11.055718232044194, 10.930464480874324, 10.754704301075268, 11.113916666666693, 11.173240223463699], "value": 11.054834254143662, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.9662, 6.441949999999999, 6.525949999999999, 6.56785, 6.675549999999999], "value": 6.546899999999999, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
08:47:08 INFO - Return code: 0
08:47:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
08:47:08 INFO - # TBPL SUCCESS #
08:47:08 INFO - Running post-action listener: _package_coverage_data
08:47:08 INFO - Running post-action listener: _resource_record_post_action
08:47:08 INFO - [mozharness: 2017-10-31 15:47:08.889590Z] Finished run-tests step (success)
08:47:08 INFO - Running post-run listener: _resource_record_post_run
08:47:08 INFO - Total resource usage - Wall time: 594s; CPU: 39.0%; Read bytes: 6246400; Write bytes: 313344000; Read time: 668; Write time: 174508
08:47:08 INFO - TinderboxPrint: CPU usage
38.8%
08:47:08 INFO - TinderboxPrint: I/O read bytes / time
6,246,400 / 668
08:47:08 INFO - TinderboxPrint: I/O write bytes / time
313,344,000 / 174,508
08:47:08 INFO - TinderboxPrint: CPU idle
2,851.5 (61.2%)
08:47:08 INFO - TinderboxPrint: CPU system
305.5 (6.6%)
08:47:08 INFO - TinderboxPrint: CPU user
1,491.5 (32.0%)
08:47:08 INFO - TinderboxPrint: Swap in / out
0 / 0
08:47:08 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 4861952; Read time: 0; Write time: 376
08:47:08 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
08:47:08 INFO - run-tests - Wall time: 575s; CPU: 40.0%; Read bytes: 6246400; Write bytes: 308482048; Read time: 668; Write time: 174132
08:47:09 INFO - Running post-run listener: _upload_blobber_files
08:47:09 INFO - Blob upload gear active.
08:47:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
08:47:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
08:47:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json']
08:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json
08:47:09 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:339: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:09 INFO - SNIMissingWarning
08:47:09 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:09 INFO - InsecurePlatformWarning
08:47:09 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
08:47:09 INFO - SubjectAltNameWarning
08:47:09 INFO - (blobuploader) - INFO - Open directory for files ...
08:47:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
08:47:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:47:09 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:47:09 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:09 INFO - InsecurePlatformWarning
08:47:09 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
08:47:09 INFO - SubjectAltNameWarning
08:47:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:14 INFO - InsecurePlatformWarning
08:47:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
08:47:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:47:14 INFO - (blobuploader) - INFO - Done attempting.
08:47:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
08:47:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:47:14 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:47:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:14 INFO - InsecurePlatformWarning
08:47:14 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
08:47:14 INFO - SubjectAltNameWarning
08:47:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:15 INFO - InsecurePlatformWarning
08:47:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
08:47:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:47:15 INFO - (blobuploader) - INFO - Done attempting.
08:47:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
08:47:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:47:15 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:47:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:15 INFO - InsecurePlatformWarning
08:47:15 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
08:47:15 INFO - SubjectAltNameWarning
08:47:16 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:16 INFO - InsecurePlatformWarning
08:47:16 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
08:47:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:47:16 INFO - (blobuploader) - INFO - Done attempting.
08:47:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
08:47:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
08:47:16 INFO - (blobuploader) - INFO - Uploading, attempt #1.
08:47:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:17 INFO - InsecurePlatformWarning
08:47:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/connection.py:344: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
08:47:17 INFO - SubjectAltNameWarning
08:47:17 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/urllib3/util/ssl_.py:137: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
08:47:17 INFO - InsecurePlatformWarning
08:47:17 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
08:47:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
08:47:17 INFO - (blobuploader) - INFO - Done attempting.
08:47:17 INFO - (blobuploader) - INFO - Iteration through files over.
08:47:17 INFO - Return code: 0
08:47:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
08:47:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
08:47:17 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9400c07e19cc10848546de036a9fdb994c36c3f966a6c370697ab4006a78e58c2add2958b5e622214e5b76c244050e05278a4edf73488bc99fef35eb3cdc7d5c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3baf76c3725ee9512ab2948aebd97e5d0540fcb5d70100fa3a19d5f23e1892b9db6035fa86668c88878933a35ed9ff9b51e904bb22aab7aef471f2a14e554798", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c2baaf218a75e3c24fbe7a2d100129e6c616d111a5e09e5e8d00c237b42ab4edc79e827a32fb5431b24dad05bcf316d4b9846437b60299660dc886e3af257a5", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04b75641f190837b843dd53190eb44cbe9c8cb7a3c683c6adf391bb73ad3a1973b5a246dc67409dc0a4e85b96e02e871f9258c0a71856b77e35ec6d68ed62868"}
08:47:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
08:47:17 INFO - Writing to file /builds/slave/test/properties/blobber_files
08:47:17 INFO - Contents:
08:47:17 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9400c07e19cc10848546de036a9fdb994c36c3f966a6c370697ab4006a78e58c2add2958b5e622214e5b76c244050e05278a4edf73488bc99fef35eb3cdc7d5c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3baf76c3725ee9512ab2948aebd97e5d0540fcb5d70100fa3a19d5f23e1892b9db6035fa86668c88878933a35ed9ff9b51e904bb22aab7aef471f2a14e554798", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c2baaf218a75e3c24fbe7a2d100129e6c616d111a5e09e5e8d00c237b42ab4edc79e827a32fb5431b24dad05bcf316d4b9846437b60299660dc886e3af257a5", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04b75641f190837b843dd53190eb44cbe9c8cb7a3c683c6adf391bb73ad3a1973b5a246dc67409dc0a4e85b96e02e871f9258c0a71856b77e35ec6d68ed62868"}
08:47:17 INFO - Running post-run listener: copy_logs_to_upload_dir
08:47:17 INFO - Copying logs to upload dir...
08:47:17 INFO - mkdir: /builds/slave/test/build/upload/logs
08:47:17 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=704.524778
========= master_lag: 0.04 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 44 secs) (at 2017-10-31 08:47:17.971414) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-31 08:47:17.974742) =========
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=2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9400c07e19cc10848546de036a9fdb994c36c3f966a6c370697ab4006a78e58c2add2958b5e622214e5b76c244050e05278a4edf73488bc99fef35eb3cdc7d5c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3baf76c3725ee9512ab2948aebd97e5d0540fcb5d70100fa3a19d5f23e1892b9db6035fa86668c88878933a35ed9ff9b51e904bb22aab7aef471f2a14e554798", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c2baaf218a75e3c24fbe7a2d100129e6c616d111a5e09e5e8d00c237b42ab4edc79e827a32fb5431b24dad05bcf316d4b9846437b60299660dc886e3af257a5", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04b75641f190837b843dd53190eb44cbe9c8cb7a3c683c6adf391bb73ad3a1973b5a246dc67409dc0a4e85b96e02e871f9258c0a71856b77e35ec6d68ed62868"}
build_url:https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011452
build_url: 'https://queue.taskcluster.net/v1/task/V1f3tdRkTwSW5Eiai-Wz0Q/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/9400c07e19cc10848546de036a9fdb994c36c3f966a6c370697ab4006a78e58c2add2958b5e622214e5b76c244050e05278a4edf73488bc99fef35eb3cdc7d5c", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3baf76c3725ee9512ab2948aebd97e5d0540fcb5d70100fa3a19d5f23e1892b9db6035fa86668c88878933a35ed9ff9b51e904bb22aab7aef471f2a14e554798", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c2baaf218a75e3c24fbe7a2d100129e6c616d111a5e09e5e8d00c237b42ab4edc79e827a32fb5431b24dad05bcf316d4b9846437b60299660dc886e3af257a5", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/04b75641f190837b843dd53190eb44cbe9c8cb7a3c683c6adf391bb73ad3a1973b5a246dc67409dc0a4e85b96e02e871f9258c0a71856b77e35ec6d68ed62868"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-31 08:47:18.004624) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-31 08:47:18.005155) =========
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=2d868d471c43b46e4fea12ad000001b6-1509463239.418165-669185067
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004566
========= master_lag: 0.08 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-31 08:47:18.088326) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-31 08:47:18.088632) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-31 08:47:18.088944) =========
========= Total master_lag: 0.26 =========