builder: mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s
slave: talos-linux64-ix-087
starttime: 1509100949.08
results: success (0)
revision: ecef003d836730c07ecfcfa25df8e71a88aec99f
========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.079563) =========
master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.080115) =========
========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.080821) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.228948) =========
========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.229469) =========
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=ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
--2017-10-27 03:42:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py
Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25
Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected.
HTTP request sent, awaiting response... 200 Script output follows
Length: 12179 (12K) [text/x-python]
Saving to: `archiver_client.py'
0K .......... . 100% 68.4M=0s
2017-10-27 03:42:29 (68.4 MB/s) - `archiver_client.py' saved [12179/12179]
program finished with exit code 0
elapsedTime=0.130581
========= master_lag: 0.04 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.401712) =========
========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.402011) =========
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=ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.019922
========= master_lag: 0.08 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:29.497018) =========
========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-10-27 03:42:29.497320) =========
bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ecef003d836730c07ecfcfa25df8e71a88aec99f --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 ecef003d836730c07ecfcfa25df8e71a88aec99f --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=ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
2017-10-27 03:42:29,542 truncating revision to first 12 chars
2017-10-27 03:42:29,542 Setting DEBUG logging.
2017-10-27 03:42:29,542 attempt 1/10
2017-10-27 03:42:29,542 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ecef003d8367?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness
2017-10-27 03:42:30,714 unpacking tar archive at: mozilla-central-ecef003d8367/testing/mozharness/
program finished with exit code 0
elapsedTime=3.525685
========= master_lag: 0.86 =========
========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-10-27 03:42:33.880661) =========
========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:33.880933) =========
script_repo_revision: ecef003d836730c07ecfcfa25df8e71a88aec99f
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:33.881279) =========
========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:33.881547) =========
========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-27 03:42:34.426516) =========
========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 14 secs) (at 2017-10-27 03:42:34.426768) =========
/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=ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
03:42:34 INFO - MultiFileLogger online at 20171027 03:42:34 in /builds/slave/test
03:42:34 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
03:42:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json.
03:42:34 INFO - {'append_to_log': False,
03:42:34 INFO - 'base_work_dir': '/builds/slave/test',
03:42:34 INFO - 'blob_upload_branch': 'Firefox-Non-PGO',
03:42:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt',
03:42:34 INFO - 'branch': 'Firefox-Non-PGO',
03:42:34 INFO - 'buildbot_json_path': 'buildprops.json',
03:42:34 INFO - 'code_coverage': False,
03:42:34 INFO - 'config_files': ('talos/linux_config.py',),
03:42:34 INFO - 'default_actions': ('clobber',
03:42:34 INFO - 'read-buildbot-config',
03:42:34 INFO - 'download-and-extract',
03:42:34 INFO - 'populate-webroot',
03:42:34 INFO - 'create-virtualenv',
03:42:34 INFO - 'install',
03:42:34 INFO - 'setup-mitmproxy',
03:42:34 INFO - 'run-tests'),
03:42:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',),
03:42:34 INFO - 'disable_ccov_upload': False,
03:42:34 INFO - 'disable_stylo': False,
03:42:34 INFO - 'download_minidump_stackwalk': True,
03:42:34 INFO - 'download_symbols': 'ondemand',
03:42:34 INFO - 'enable_stylo': False,
03:42:34 INFO - 'enable_webrender': False,
03:42:34 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'},
03:42:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub',
03:42:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'),
03:42:34 INFO - 'gecko_profile': False,
03:42:34 INFO - 'gecko_profile_interval': 0,
03:42:34 INFO - 'installer_path': 'installer.exe',
03:42:34 INFO - 'log_level': 'info',
03:42:34 INFO - 'log_name': 'talos',
03:42:34 INFO - 'log_to_console': True,
03:42:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk',
03:42:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest',
03:42:34 INFO - 'opt_config_files': (),
03:42:34 INFO - 'pip_index': False,
03:42:34 INFO - 'suite': 'g4-stylo-disabled-e10s',
03:42:34 INFO - 'system_bits': '32',
03:42:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'),
03:42:34 INFO - 'title': 'talos-linux64-ix-087',
03:42:34 INFO - 'tooltool_cache': '/builds/tooltool_cache',
03:42:34 INFO - 'use_talos_json': True,
03:42:34 INFO - 'verify': 'False',
03:42:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv',
03:42:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None},
03:42:34 INFO - 'work_dir': 'build'}
03:42:34 INFO - [mozharness: 2017-10-27 10:42:34.611936Z] Running clobber step.
03:42:34 INFO - Running pre-action listener: _resource_record_pre_action
03:42:34 INFO - Running main action method: clobber
03:42:34 INFO - rmtree: /builds/slave/test/build
03:42:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1
03:42:35 INFO - Running post-action listener: _resource_record_post_action
03:42:35 INFO - [mozharness: 2017-10-27 10:42:35.667799Z] Finished clobber step (success)
03:42:35 INFO - [mozharness: 2017-10-27 10:42:35.667895Z] Running read-buildbot-config step.
03:42:35 INFO - Running pre-action listener: _resource_record_pre_action
03:42:35 INFO - Running main action method: read_buildbot_config
03:42:35 INFO - Using buildbot properties:
03:42:35 INFO - {
03:42:35 INFO - "project": "",
03:42:35 INFO - "product": "firefox",
03:42:35 INFO - "who": "archaeopteryx@coole-files.de",
03:42:35 INFO - "installer_path": "public/build/target.tar.bz2",
03:42:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-g4-stylo-disabled-e10s",
03:42:35 INFO - "repository": "https://hg.mozilla.org/mozilla-central",
03:42:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos g4-stylo-disabled-e10s",
03:42:35 INFO - "stage_platform": "linux64",
03:42:35 INFO - "basedir": "/builds/slave/test",
03:42:35 INFO - "buildnumber": 121,
03:42:35 INFO - "platform": "ubuntu64_hw",
03:42:35 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/",
03:42:35 INFO - "slavebuilddir": "test",
03:42:35 INFO - "taskId": "U2Ay-ouuSIWnQnAZBe5kaw",
03:42:35 INFO - "branch": "mozilla-central",
03:42:35 INFO - "script_repo_revision": "production",
03:42:35 INFO - "revision": "ecef003d836730c07ecfcfa25df8e71a88aec99f",
03:42:35 INFO - "slavename": "talos-linux64-ix-087",
03:42:35 INFO - "repo_path": "mozilla-central"
03:42:35 INFO - }
03:42:35 INFO - Finding installer, test and symbols from parent task.
03:42:35 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U2Ay-ouuSIWnQnAZBe5kaw'}, attempt #1
03:42:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U2Ay-ouuSIWnQnAZBe5kaw'}, attempt #1
03:42:36 INFO - Task dependencies: VQ1N5TAgQn61fQ4Cn4LxFw
03:42:36 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw'}, attempt #1
03:42:37 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2
03:42:37 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.test_packages.json
03:42:37 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.crashreporter-symbols.zip
03:42:37 INFO - Running post-action listener: _resource_record_post_action
03:42:37 INFO - [mozharness: 2017-10-27 10:42:37.037022Z] Finished read-buildbot-config step (success)
03:42:37 INFO - [mozharness: 2017-10-27 10:42:37.037240Z] Running download-and-extract step.
03:42:37 INFO - Running pre-action listener: _resource_record_pre_action
03:42:37 INFO - Running main action method: download_and_extract
03:42:37 INFO - mkdir: /builds/slave/test/build/tests
03:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:42:37 INFO - trying https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.test_packages.json
03:42:37 INFO - Downloading https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json
03:42:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1
03:42:37 INFO - Downloaded 989 bytes.
03:42:37 INFO - Reading from file /builds/slave/test/build/target.test_packages.json
03:42:37 INFO - Using the following test package requirements:
03:42:37 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'],
03:42:37 INFO - u'common': [u'target.common.tests.zip'],
03:42:37 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'],
03:42:37 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'],
03:42:37 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'],
03:42:37 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'],
03:42:37 INFO - u'mozbase': [u'target.common.tests.zip'],
03:42:37 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'],
03:42:37 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'],
03:42:37 INFO - u'web-platform': [u'target.common.tests.zip',
03:42:37 INFO - u'target.web-platform.tests.tar.gz'],
03:42:37 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']}
03:42:37 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos']
03:42:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.common.tests.zip
03:42:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.common.tests.zip'}, attempt #1
03:42:37 INFO - Fetch https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.common.tests.zip into memory
03:42:44 INFO - Content-Length response header: 56326496
03:42:44 INFO - Bytes received: 56326496
03:42:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.talos.tests.zip
03:42:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.talos.tests.zip'}, attempt #1
03:42:54 INFO - Fetch https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.talos.tests.zip into memory
03:42:58 INFO - Content-Length response header: 18771897
03:42:58 INFO - Bytes received: 18771897
03:42: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')]}
03:42:59 INFO - trying https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2
03:42:59 INFO - Downloading https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2
03:42:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1
03:43:13 INFO - Downloaded 62649007 bytes.
03:43:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2
03:43:13 INFO - mkdir: /builds/slave/test/properties
03:43:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url
03:43:13 INFO - Writing to file /builds/slave/test/properties/build_url
03:43:13 INFO - Contents:
03:43:13 INFO - build_url:https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2
03:43:13 INFO - Running post-action listener: _resource_record_post_action
03:43:13 INFO - Running post-action listener: find_tests_for_verification
03:43:13 INFO - Running post-action listener: set_extra_try_arguments
03:43:13 INFO - [mozharness: 2017-10-27 10:43:13.198083Z] Finished download-and-extract step (success)
03:43:13 INFO - [mozharness: 2017-10-27 10:43:13.198303Z] Running populate-webroot step.
03:43:13 INFO - Running pre-action listener: _resource_record_pre_action
03:43:13 INFO - Running main action method: populate_webroot
03:43:13 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']},
03:43:13 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['tresize',
03:43:13 INFO - 'tcanvasmark']},
03:43:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']},
03:43:13 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['dromaeo_css',
03:43:13 INFO - 'kraken']},
03:43:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:43:13 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']},
03:43:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'tests': ['damp', 'tps']},
03:43:13 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['damp', 'tps']},
03:43:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']},
03:43:13 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['dromaeo_dom']},
03:43:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']},
03:43:13 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['basic_compositor_video',
03:43:13 INFO - 'glvideo']},
03:43:13 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']},
03:43:13 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['ts_paint_webext',
03:43:13 INFO - 'tp5o_webext']},
03:43:13 INFO - 'h1-e10s': {'tests': ['ts_paint_heavy']},
03:43:13 INFO - 'h2-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:43:13 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:43:13 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:43:13 INFO - 'talos_options': ['--mitmproxy',
03:43:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:43:13 INFO - '--firstNonBlankPaint'],
03:43:13 INFO - 'tests': ['tp6_google_heavy',
03:43:13 INFO - 'tp6_youtube_heavy',
03:43:13 INFO - 'tp6_amazon_heavy',
03:43:13 INFO - 'tp6_facebook_heavy']},
03:43:13 INFO - 'other-e10s': {'tests': ['a11yr',
03:43:13 INFO - 'ts_paint',
03:43:13 INFO - 'tpaint',
03:43:13 INFO - 'sessionrestore',
03:43:13 INFO - 'sessionrestore_many_windows',
03:43:13 INFO - 'sessionrestore_no_auto_restore',
03:43:13 INFO - 'tabpaint',
03:43:13 INFO - 'cpstartup']},
03:43:13 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['a11yr',
03:43:13 INFO - 'ts_paint',
03:43:13 INFO - 'tpaint',
03:43:13 INFO - 'sessionrestore',
03:43:13 INFO - 'sessionrestore_many_windows',
03:43:13 INFO - 'sessionrestore_no_auto_restore',
03:43:13 INFO - 'tabpaint',
03:43:13 INFO - 'cpstartup']},
03:43:13 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']},
03:43:13 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']},
03:43:13 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['perf_reftest_singletons']},
03:43:13 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['perf_reftest']},
03:43:13 INFO - 'speedometer-e10s': {'tests': ['speedometer']},
03:43:13 INFO - 'speedometer-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['speedometer']},
03:43:13 INFO - 'svgr-e10s': {'tests': ['tsvgx',
03:43:13 INFO - 'tsvgr_opacity',
03:43:13 INFO - 'tart',
03:43:13 INFO - 'tscrollx',
03:43:13 INFO - 'tsvg_static']},
03:43:13 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['tsvgx',
03:43:13 INFO - 'tsvgr_opacity',
03:43:13 INFO - 'tart',
03:43:13 INFO - 'tscrollx',
03:43:13 INFO - 'tsvg_static']},
03:43:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']},
03:43:13 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'talos_options': ['--disable-stylo'],
03:43:13 INFO - 'tests': ['tp5o']},
03:43:13 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:43:13 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:43:13 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:43:13 INFO - 'talos_options': ['--mitmproxy',
03:43:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:43:13 INFO - '--firstNonBlankPaint'],
03:43:13 INFO - 'tests': ['tp6_google',
03:43:13 INFO - 'tp6_youtube',
03:43:13 INFO - 'tp6_amazon',
03:43:13 INFO - 'tp6_facebook']},
03:43:13 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:43:13 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:43:13 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:43:13 INFO - 'talos_options': ['--disable-stylo',
03:43:13 INFO - '--mitmproxy',
03:43:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:43:13 INFO - '--firstNonBlankPaint'],
03:43:13 INFO - 'tests': ['tp6_google',
03:43:13 INFO - 'tp6_youtube',
03:43:13 INFO - 'tp6_amazon',
03:43:13 INFO - 'tp6_facebook']},
03:43:13 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip',
03:43:13 INFO - 'mitmproxy_release_bin_linux64': 'mitmproxy-2.0.2-linux.tar.gz',
03:43:13 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz',
03:43:13 INFO - 'talos_options': ['--stylo-threads=1',
03:43:13 INFO - '--mitmproxy',
03:43:13 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp',
03:43:13 INFO - '--firstNonBlankPaint'],
03:43:13 INFO - 'tests': ['tp6_google',
03:43:13 INFO - 'tp6_youtube',
03:43:13 INFO - 'tp6_amazon',
03:43:13 INFO - 'tp6_facebook']},
03:43:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'talos_options': ['--xperf_path',
03:43:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:43:13 INFO - 'tests': ['tp5n']},
03:43:13 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip',
03:43:13 INFO - 'talos_options': ['--disable-stylo',
03:43:13 INFO - '--xperf_path',
03:43:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'],
03:43:13 INFO - 'tests': ['tp5n']}}}
03:43:13 INFO - Running post-action listener: _resource_record_post_action
03:43:13 INFO - [mozharness: 2017-10-27 10:43:13.224149Z] Finished populate-webroot step (success)
03:43:13 INFO - [mozharness: 2017-10-27 10:43:13.224237Z] Running create-virtualenv step.
03:43:13 INFO - Running pre-action listener: _resource_record_pre_action
03:43:13 INFO - Running main action method: create_virtualenv
03:43:13 INFO - Creating virtualenv /builds/slave/test/build/venv
03:43:13 INFO - Running command: ['/tools/buildbot/bin/python', '/builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py', '--always-copy', '/builds/slave/test/build/venv'] in /builds/slave/test/build
03:43:13 INFO - Copy/paste: /tools/buildbot/bin/python /builds/slave/test/scripts/external_tools/virtualenv/virtualenv.py --always-copy /builds/slave/test/build/venv
03:43:13 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'}
03:43:13 INFO - Using real prefix '/usr'
03:43:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python
03:43:13 INFO - Overwriting /builds/slave/test/build/venv/lib/python2.7/distutils/__init__.py with new content
03:43:15 INFO - Installing setuptools, pip, wheel...done.
03:43:15 INFO - Return code: 0
03:43:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv
03:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'pip>=1.5'] in /builds/slave/test/build
03:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org pip>=1.5
03:43:15 INFO - Using env: {'DISPLAY': ':0',
03:43:15 INFO - 'HOME': '/home/cltbld',
03:43:15 INFO - 'LANG': 'en_US.UTF-8',
03:43:15 INFO - 'LANGUAGE': 'en_US:en',
03:43:15 INFO - 'LOGNAME': 'cltbld',
03:43:15 INFO - 'MAIL': '/var/mail/cltbld',
03:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:15 INFO - 'MOZ_NO_REMOTE': '1',
03:43:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:15 INFO - 'NO_EM_RESTART': '1',
03:43:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:15 INFO - 'PWD': '/builds/slave/test',
03:43:15 INFO - 'SHELL': '/bin/bash',
03:43:15 INFO - 'SHLVL': '1',
03:43:15 INFO - 'TERM': 'linux',
03:43:15 INFO - 'TMOUT': '86400',
03:43:15 INFO - 'USER': 'cltbld',
03:43:15 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:15 INFO - '_': '/tools/buildbot/bin/python'}
03:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages
03:43:15 INFO - Return code: 0
03:43:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv
03:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', '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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'psutil>=3.1.1'] in /builds/slave/test/build
03:43:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org psutil>=3.1.1
03:43:15 INFO - Using env: {'DISPLAY': ':0',
03:43:15 INFO - 'HOME': '/home/cltbld',
03:43:15 INFO - 'LANG': 'en_US.UTF-8',
03:43:15 INFO - 'LANGUAGE': 'en_US:en',
03:43:15 INFO - 'LOGNAME': 'cltbld',
03:43:15 INFO - 'MAIL': '/var/mail/cltbld',
03:43:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:15 INFO - 'MOZ_NO_REMOTE': '1',
03:43:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:15 INFO - 'NO_EM_RESTART': '1',
03:43:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:15 INFO - 'PWD': '/builds/slave/test',
03:43:15 INFO - 'SHELL': '/bin/bash',
03:43:15 INFO - 'SHLVL': '1',
03:43:15 INFO - 'TERM': 'linux',
03:43:15 INFO - 'TMOUT': '86400',
03:43:15 INFO - 'USER': 'cltbld',
03:43:15 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:15 INFO - '_': '/tools/buildbot/bin/python'}
03:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:16 INFO - Collecting psutil>=3.1.1
03:43:17 INFO - Installing collected packages: psutil
03:43:17 INFO - Successfully installed psutil-3.1.1
03:43:17 INFO - Return code: 0
03:43:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv
03:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:17 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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'mozsystemmonitor==0.3'] in /builds/slave/test/build
03:43:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org mozsystemmonitor==0.3
03:43:17 INFO - Using env: {'DISPLAY': ':0',
03:43:17 INFO - 'HOME': '/home/cltbld',
03:43:17 INFO - 'LANG': 'en_US.UTF-8',
03:43:17 INFO - 'LANGUAGE': 'en_US:en',
03:43:17 INFO - 'LOGNAME': 'cltbld',
03:43:17 INFO - 'MAIL': '/var/mail/cltbld',
03:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:17 INFO - 'MOZ_NO_REMOTE': '1',
03:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:17 INFO - 'NO_EM_RESTART': '1',
03:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:17 INFO - 'PWD': '/builds/slave/test',
03:43:17 INFO - 'SHELL': '/bin/bash',
03:43:17 INFO - 'SHLVL': '1',
03:43:17 INFO - 'TERM': 'linux',
03:43:17 INFO - 'TMOUT': '86400',
03:43:17 INFO - 'USER': 'cltbld',
03:43:17 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:17 INFO - '_': '/tools/buildbot/bin/python'}
03:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:17 INFO - Collecting mozsystemmonitor==0.3
03:43:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3)
03:43:18 INFO - Installing collected packages: mozsystemmonitor
03:43:18 INFO - Successfully installed mozsystemmonitor-0.3
03:43:18 INFO - Return code: 0
03:43:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv
03:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:18 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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'jsonschema==2.5.1'] in /builds/slave/test/build
03:43:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org jsonschema==2.5.1
03:43:18 INFO - Using env: {'DISPLAY': ':0',
03:43:18 INFO - 'HOME': '/home/cltbld',
03:43:18 INFO - 'LANG': 'en_US.UTF-8',
03:43:18 INFO - 'LANGUAGE': 'en_US:en',
03:43:18 INFO - 'LOGNAME': 'cltbld',
03:43:18 INFO - 'MAIL': '/var/mail/cltbld',
03:43:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:18 INFO - 'MOZ_NO_REMOTE': '1',
03:43:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:18 INFO - 'NO_EM_RESTART': '1',
03:43:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:18 INFO - 'PWD': '/builds/slave/test',
03:43:18 INFO - 'SHELL': '/bin/bash',
03:43:18 INFO - 'SHLVL': '1',
03:43:18 INFO - 'TERM': 'linux',
03:43:18 INFO - 'TMOUT': '86400',
03:43:18 INFO - 'USER': 'cltbld',
03:43:18 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:18 INFO - '_': '/tools/buildbot/bin/python'}
03:43:19 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:19 INFO - Collecting jsonschema==2.5.1
03:43:20 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/jsonschema-2.5.1-py2.py3-none-any.whl
03:43:20 INFO - Collecting functools32; python_version == "2.7" (from jsonschema==2.5.1)
03:43:21 INFO - Installing collected packages: functools32, jsonschema
03:43:21 INFO - Successfully installed functools32-3.2.3.post2 jsonschema-2.5.1
03:43:21 INFO - Return code: 0
03:43:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv
03:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:21 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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'functools32==3.2.3-2'] in /builds/slave/test/build
03:43:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org functools32==3.2.3-2
03:43:21 INFO - Using env: {'DISPLAY': ':0',
03:43:21 INFO - 'HOME': '/home/cltbld',
03:43:21 INFO - 'LANG': 'en_US.UTF-8',
03:43:21 INFO - 'LANGUAGE': 'en_US:en',
03:43:21 INFO - 'LOGNAME': 'cltbld',
03:43:21 INFO - 'MAIL': '/var/mail/cltbld',
03:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:21 INFO - 'MOZ_NO_REMOTE': '1',
03:43:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:21 INFO - 'NO_EM_RESTART': '1',
03:43:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:21 INFO - 'PWD': '/builds/slave/test',
03:43:21 INFO - 'SHELL': '/bin/bash',
03:43:21 INFO - 'SHLVL': '1',
03:43:21 INFO - 'TERM': 'linux',
03:43:21 INFO - 'TMOUT': '86400',
03:43:21 INFO - 'USER': 'cltbld',
03:43:21 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:21 INFO - '_': '/tools/buildbot/bin/python'}
03:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages
03:43:21 INFO - Return code: 0
03:43:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv
03:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:21 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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org', 'blobuploader==1.2.4'] in /builds/slave/test/build
03:43:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org blobuploader==1.2.4
03:43:21 INFO - Using env: {'DISPLAY': ':0',
03:43:21 INFO - 'HOME': '/home/cltbld',
03:43:21 INFO - 'LANG': 'en_US.UTF-8',
03:43:21 INFO - 'LANGUAGE': 'en_US:en',
03:43:21 INFO - 'LOGNAME': 'cltbld',
03:43:21 INFO - 'MAIL': '/var/mail/cltbld',
03:43:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:21 INFO - 'MOZ_NO_REMOTE': '1',
03:43:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:21 INFO - 'NO_EM_RESTART': '1',
03:43:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:21 INFO - 'PWD': '/builds/slave/test',
03:43:21 INFO - 'SHELL': '/bin/bash',
03:43:21 INFO - 'SHLVL': '1',
03:43:21 INFO - 'TERM': 'linux',
03:43:21 INFO - 'TMOUT': '86400',
03:43:21 INFO - 'USER': 'cltbld',
03:43:21 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:21 INFO - '_': '/tools/buildbot/bin/python'}
03:43:22 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:22 INFO - Collecting blobuploader==1.2.4
03:43:23 INFO - Collecting requests==1.2.3. (from blobuploader==1.2.4)
03:43:24 INFO - Collecting docopt==0.6.1 (from blobuploader==1.2.4)
03:43:25 INFO - Installing collected packages: requests, docopt, blobuploader
03:43:25 INFO - Successfully installed blobuploader-1.2.4 docopt-0.6.1 requests-1.2.3
03:43:25 INFO - Return code: 0
03:43:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:25 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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
03:43:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:43:25 INFO - Using env: {'DISPLAY': ':0',
03:43:25 INFO - 'HOME': '/home/cltbld',
03:43:25 INFO - 'LANG': 'en_US.UTF-8',
03:43:25 INFO - 'LANGUAGE': 'en_US:en',
03:43:25 INFO - 'LOGNAME': 'cltbld',
03:43:25 INFO - 'MAIL': '/var/mail/cltbld',
03:43:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:25 INFO - 'MOZ_NO_REMOTE': '1',
03:43:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:25 INFO - 'NO_EM_RESTART': '1',
03:43:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:25 INFO - 'PWD': '/builds/slave/test',
03:43:25 INFO - 'SHELL': '/bin/bash',
03:43:25 INFO - 'SHLVL': '1',
03:43:25 INFO - 'TERM': 'linux',
03:43:25 INFO - 'TMOUT': '86400',
03:43:25 INFO - 'USER': 'cltbld',
03:43:25 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:25 INFO - '_': '/tools/buildbot/bin/python'}
03:43:26 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:43:26 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:43:27 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:43:28 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:43:29 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:43:29 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:43:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion
03:43:29 INFO - Running setup.py install for manifestparser: started
03:43:29 INFO - Running setup.py install for manifestparser: finished with status 'done'
03:43:29 INFO - Running setup.py install for mozcrash: started
03:43:29 INFO - Running setup.py install for mozcrash: finished with status 'done'
03:43:29 INFO - Running setup.py install for mozdebug: started
03:43:30 INFO - Running setup.py install for mozdebug: finished with status 'done'
03:43:30 INFO - Running setup.py install for mozdevice: started
03:43:30 INFO - Running setup.py install for mozdevice: finished with status 'done'
03:43:30 INFO - Running setup.py install for mozfile: started
03:43:30 INFO - Running setup.py install for mozfile: finished with status 'done'
03:43:30 INFO - Running setup.py install for mozhttpd: started
03:43:30 INFO - Running setup.py install for mozhttpd: finished with status 'done'
03:43:30 INFO - Running setup.py install for mozinfo: started
03:43:30 INFO - Running setup.py install for mozinfo: finished with status 'done'
03:43:30 INFO - Running setup.py install for mozInstall: started
03:43:31 INFO - Running setup.py install for mozInstall: finished with status 'done'
03:43:31 INFO - Running setup.py install for mozleak: started
03:43:31 INFO - Running setup.py install for mozleak: finished with status 'done'
03:43:31 INFO - Running setup.py install for mozlog: started
03:43:31 INFO - Running setup.py install for mozlog: finished with status 'done'
03:43:31 INFO - Running setup.py install for moznetwork: started
03:43:31 INFO - Running setup.py install for moznetwork: finished with status 'done'
03:43:31 INFO - Running setup.py install for mozprocess: started
03:43:32 INFO - Running setup.py install for mozprocess: finished with status 'done'
03:43:32 INFO - Running setup.py install for mozprofile: started
03:43:32 INFO - Running setup.py install for mozprofile: finished with status 'done'
03:43:32 INFO - Running setup.py install for mozrunner: started
03:43:32 INFO - Running setup.py install for mozrunner: finished with status 'done'
03:43:32 INFO - Running setup.py install for mozscreenshot: started
03:43:32 INFO - Running setup.py install for mozscreenshot: finished with status 'done'
03:43:32 INFO - Running setup.py install for moztest: started
03:43:32 INFO - Running setup.py install for moztest: finished with status 'done'
03:43:32 INFO - Running setup.py install for mozversion: started
03:43:33 INFO - Running setup.py install for mozversion: finished with status 'done'
03:43:33 INFO - Successfully installed manifestparser-1.2 mozInstall-1.14 mozcrash-1.0 mozdebug-0.1 mozdevice-0.51 mozfile-1.2 mozhttpd-0.7 mozinfo-0.10 mozleak-0.1 mozlog-3.5 moznetwork-0.27 mozprocess-0.25 mozprofile-0.29 mozrunner-6.13 mozscreenshot-0.1 moztest-0.8 mozversion-1.4
03:43:33 INFO - Return code: 0
03:43:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/config
03:43:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:43:33 INFO - Using env: {'DISPLAY': ':0',
03:43:33 INFO - 'HOME': '/home/cltbld',
03:43:33 INFO - 'LANG': 'en_US.UTF-8',
03:43:33 INFO - 'LANGUAGE': 'en_US:en',
03:43:33 INFO - 'LOGNAME': 'cltbld',
03:43:33 INFO - 'MAIL': '/var/mail/cltbld',
03:43:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:33 INFO - 'MOZ_NO_REMOTE': '1',
03:43:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:33 INFO - 'NO_EM_RESTART': '1',
03:43:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:33 INFO - 'PWD': '/builds/slave/test',
03:43:33 INFO - 'SHELL': '/bin/bash',
03:43:33 INFO - 'SHLVL': '1',
03:43:33 INFO - 'TERM': 'linux',
03:43:33 INFO - 'TMOUT': '86400',
03:43:33 INFO - 'USER': 'cltbld',
03:43:33 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:33 INFO - '_': '/tools/buildbot/bin/python'}
03:43:33 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:33 INFO - Processing /builds/slave/test/build/tests/mozbase/manifestparser
03:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.2 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozcrash
03:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdebug
03:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
03:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozdevice
03:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozfile
03:43:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5))
03:43:34 INFO - Processing /builds/slave/test/build/tests/mozbase/mozhttpd
03:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6))
03:43:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinfo
03:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7))
03:43:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozinstall
03:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.14 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
03:43:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozleak
03:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9))
03:43:35 INFO - Processing /builds/slave/test/build/tests/mozbase/mozlog
03:43:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:43:35 INFO - Processing /builds/slave/test/build/tests/mozbase/moznetwork
03:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11))
03:43:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprocess
03:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12))
03:43:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozprofile
03:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.29 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13))
03:43:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozrunner
03:43:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14))
03:43:36 INFO - Processing /builds/slave/test/build/tests/mozbase/mozscreenshot
03:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15))
03:43:37 INFO - Processing /builds/slave/test/build/tests/mozbase/moztest
03:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16))
03:43:37 INFO - Processing /builds/slave/test/build/tests/mozbase/mozversion
03:43:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17))
03:43:37 INFO - Collecting six>=1.10.0 (from manifestparser==1.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1))
03:43:38 INFO - Downloading http://pypi.pvt.build.mozilla.org/pub/six-1.10.0-py2.py3-none-any.whl
03:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2))
03:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3))
03:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4))
03:43:38 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.14->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8))
03:43:38 INFO - Collecting blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10))
03:43:39 INFO - Installing collected packages: six, blessings
03:43:39 INFO - Successfully installed blessings-1.6 six-1.10.0
03:43:39 INFO - Return code: 0
03:43:39 INFO - Done creating virtualenv /builds/slave/test/build/venv.
03:43:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:43:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:43:40 INFO - Reading from file tmpfile_stdout
03:43:40 INFO - Reading from file tmpfile_stderr
03:43:40 INFO - Current package versions:
03:43:40 INFO - blessings == 1.6
03:43:40 INFO - blobuploader == 1.2.4
03:43:40 INFO - docopt == 0.6.1
03:43:40 INFO - functools32 == 3.2.3.post2
03:43:40 INFO - jsonschema == 2.5.1
03:43:40 INFO - manifestparser == 1.2
03:43:40 INFO - mozInstall == 1.14
03:43:40 INFO - mozcrash == 1.0
03:43:40 INFO - mozdebug == 0.1
03:43:40 INFO - mozdevice == 0.51
03:43:40 INFO - mozfile == 1.2
03:43:40 INFO - mozhttpd == 0.7
03:43:40 INFO - mozinfo == 0.10
03:43:40 INFO - mozleak == 0.1
03:43:40 INFO - mozlog == 3.5
03:43:40 INFO - moznetwork == 0.27
03:43:40 INFO - mozprocess == 0.25
03:43:40 INFO - mozprofile == 0.29
03:43:40 INFO - mozrunner == 6.13
03:43:40 INFO - mozscreenshot == 0.1
03:43:40 INFO - mozsystemmonitor == 0.3
03:43:40 INFO - moztest == 0.8
03:43:40 INFO - mozversion == 1.4
03:43:40 INFO - psutil == 3.1.1
03:43:40 INFO - requests == 1.2.3
03:43:40 INFO - six == 1.10.0
03:43:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv
03:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:43:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43:40 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 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '--trusted-host', 'pypi.pub.build.mozilla.org', '--trusted-host', 'pypi.pvt.build.mozilla.org'] in /builds/slave/test/build/tests/talos
03:43:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub --trusted-host pypi.pub.build.mozilla.org --trusted-host pypi.pvt.build.mozilla.org
03:43:40 INFO - Using env: {'DISPLAY': ':0',
03:43:40 INFO - 'HOME': '/home/cltbld',
03:43:40 INFO - 'LANG': 'en_US.UTF-8',
03:43:40 INFO - 'LANGUAGE': 'en_US:en',
03:43:40 INFO - 'LOGNAME': 'cltbld',
03:43:40 INFO - 'MAIL': '/var/mail/cltbld',
03:43:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:40 INFO - 'MOZ_NO_REMOTE': '1',
03:43:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:40 INFO - 'NO_EM_RESTART': '1',
03:43:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:40 INFO - 'PWD': '/builds/slave/test',
03:43:40 INFO - 'SHELL': '/bin/bash',
03:43:40 INFO - 'SHLVL': '1',
03:43:40 INFO - 'TERM': 'linux',
03:43:40 INFO - 'TMOUT': '86400',
03:43:40 INFO - 'USER': 'cltbld',
03:43:40 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:40 INFO - '_': '/tools/buildbot/bin/python'}
03:43:40 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
03:43:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9))
03:43:40 INFO - Collecting simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10))
03:43:41 INFO - Collecting requests>=2.9.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 11))
03:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1))
03:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4))
03:43:42 INFO - Requirement already satisfied (use --upgrade to upgrade): six>=1.10.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozprofile>=0.25->-r /builds/slave/test/build/tests/talos/requirements.txt (line 8))
03:43:42 INFO - Installing collected packages: simplejson, requests
03:43:42 INFO - Found existing installation: requests 1.2.3
03:43:42 INFO - Uninstalling requests-1.2.3:
03:43:42 INFO - Successfully uninstalled requests-1.2.3
03:43:43 INFO - Successfully installed requests-2.13.0 simplejson-3.3.0
03:43:43 INFO - Return code: 0
03:43:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv
03:43: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')]}
03:43:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org
03:43: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')]}
03:43:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org
03:43: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x14281f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1795cc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, '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': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1
03:43: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', 'jsonschema'] in /builds/slave/test/build
03:43: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 jsonschema
03:43:43 INFO - Using env: {'DISPLAY': ':0',
03:43:43 INFO - 'HOME': '/home/cltbld',
03:43:43 INFO - 'LANG': 'en_US.UTF-8',
03:43:43 INFO - 'LANGUAGE': 'en_US:en',
03:43:43 INFO - 'LOGNAME': 'cltbld',
03:43:43 INFO - 'MAIL': '/var/mail/cltbld',
03:43:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:43:43 INFO - 'MOZ_NO_REMOTE': '1',
03:43:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:43:43 INFO - 'NO_EM_RESTART': '1',
03:43:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:43:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:43:43 INFO - 'PWD': '/builds/slave/test',
03:43:43 INFO - 'SHELL': '/bin/bash',
03:43:43 INFO - 'SHLVL': '1',
03:43:43 INFO - 'TERM': 'linux',
03:43:43 INFO - 'TMOUT': '86400',
03:43:43 INFO - 'USER': 'cltbld',
03:43:43 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:43:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:43:43 INFO - '_': '/tools/buildbot/bin/python'}
03:43:43 INFO - Ignoring indexes: https://pypi.python.org/simple
03:43:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages
03:43:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32; python_version == "2.7" in ./venv/lib/python2.7/site-packages (from jsonschema)
03:43:43 INFO - Return code: 0
03:43:43 INFO - Running post-action listener: _resource_record_post_action
03:43:43 INFO - Running post-action listener: _start_resource_monitoring
03:43:43 INFO - Starting resource monitoring.
03:43:43 INFO - [mozharness: 2017-10-27 10:43:43.755230Z] Finished create-virtualenv step (success)
03:43:43 INFO - [mozharness: 2017-10-27 10:43:43.755687Z] Running install step.
03:43:43 INFO - Running pre-action listener: _resource_record_pre_action
03:43:43 INFO - Running main action method: install
03:43:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze']
03:43:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze
03:43:44 INFO - Reading from file tmpfile_stdout
03:43:44 INFO - Reading from file tmpfile_stderr
03:43:44 INFO - Detecting whether we're running mozinstall >=1.0...
03:43:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h']
03:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h
03:43:44 INFO - Reading from file tmpfile_stdout
03:43:44 INFO - Output received:
03:43:44 INFO - Usage: mozinstall [options] installer
03:43:44 INFO - Options:
03:43:44 INFO - -h, --help show this help message and exit
03:43:44 INFO - -d DEST, --destination=DEST
03:43:44 INFO - Directory to install application into. [default:
03:43:44 INFO - "/builds/slave/test"]
03:43:44 INFO - --app=APP Application being installed. [default: firefox]
03:43:44 INFO - mkdir: /builds/slave/test/build/application
03:43:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application']
03:43:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application
03:44:02 INFO - Reading from file tmpfile_stdout
03:44:02 INFO - Output received:
03:44:02 INFO - /builds/slave/test/build/application/firefox/firefox
03:44:02 INFO - Running post-action listener: _resource_record_post_action
03:44:02 INFO - [mozharness: 2017-10-27 10:44:02.609127Z] Finished install step (success)
03:44:02 INFO - [mozharness: 2017-10-27 10:44:02.609375Z] Running setup-mitmproxy step.
03:44:02 INFO - Running pre-action listener: _resource_record_pre_action
03:44:02 INFO - Running main action method: setup_mitmproxy
03:44:02 INFO - Skipping: mitmproxy is not required
03:44:02 INFO - Running post-action listener: _resource_record_post_action
03:44:02 INFO - [mozharness: 2017-10-27 10:44:02.610496Z] Finished setup-mitmproxy step (success)
03:44:02 INFO - [mozharness: 2017-10-27 10:44:02.610846Z] Running run-tests step.
03:44:02 INFO - Running pre-action listener: _resource_record_pre_action
03:44:02 INFO - Running pre-action listener: _set_gcov_prefix
03:44:02 INFO - Running main action method: run_tests
03:44:02 WARNING - Try message not found.
03:44:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version']
03:44:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version
03:44:02 INFO - Python 2.7.3
03:44:02 INFO - Return code: 0
03:44:02 INFO - grabbing minidump binary from tooltool
03:44:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]}
03:44:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1792e60>, '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 0x1793d60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17941f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1
03:44:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build
03:44:02 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache
03:44:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600
03:44:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache
03:44:02 INFO - Return code: 0
03:44:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755
03:44:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir
03:44:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir
03:44:02 INFO - ENV: RUST_BACKTRACE is now full
03:44:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk
03:44:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir
03:44:02 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/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] in /builds/slave/test/build
03:44:02 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/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-087 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log
03:44:02 INFO - Using env: {'DISPLAY': ':0',
03:44:02 INFO - 'HOME': '/home/cltbld',
03:44:02 INFO - 'LANG': 'en_US.UTF-8',
03:44:02 INFO - 'LANGUAGE': 'en_US:en',
03:44:02 INFO - 'LOGNAME': 'cltbld',
03:44:02 INFO - 'MAIL': '/var/mail/cltbld',
03:44:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:44:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:44:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:02 INFO - 'MOZ_NO_REMOTE': '1',
03:44:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:44:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:02 INFO - 'NO_EM_RESTART': '1',
03:44:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:02 INFO - 'PWD': '/builds/slave/test',
03:44:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:44:02 INFO - 'RUST_BACKTRACE': 'full',
03:44:02 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:44:02 INFO - 'SHELL': '/bin/bash',
03:44:02 INFO - 'SHLVL': '1',
03:44:02 INFO - 'TERM': 'linux',
03:44:02 INFO - 'TMOUT': '86400',
03:44:02 INFO - 'USER': 'cltbld',
03:44:02 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:44:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:02 INFO - '_': '/tools/buildbot/bin/python'}
03:44:02 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/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-087', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log'] with output_timeout 3600
03:44:03 INFO - mozversion application_buildid: 20171027094643
03:44:03 INFO - mozversion application_changeset: ecef003d836730c07ecfcfa25df8e71a88aec99f
03:44:03 INFO - mozversion application_display_name: Nightly
03:44:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
03:44:03 INFO - mozversion application_name: Firefox
03:44:03 INFO - mozversion application_remotingname: firefox
03:44:03 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central
03:44:03 INFO - mozversion application_vendor: Mozilla
03:44:03 INFO - mozversion application_version: 58.0a1
03:44:03 INFO - mozversion platform_buildid: 20171027094643
03:44:03 INFO - mozversion platform_changeset: ecef003d836730c07ecfcfa25df8e71a88aec99f
03:44:03 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central
03:44:03 INFO - mozversion platform_version: 58.0a1
03:44:03 INFO - using testdate: 1509101043
03:44:03 INFO - actual date: 1509101043
03:44:03 INFO - starting webserver on 'localhost:46289'
03:44:03 INFO - SUITE-START | Running 2 tests
03:44:03 INFO - TEST-START | basic_compositor_video
03:44:03 INFO - Initialising browser for basic_compositor_video test...
03:44:03 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html -profile /tmp/tmpykulFr/profile
03:44:03 INFO - TEST-INFO | started process 8538 (/builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html)
03:44:09 INFO - TEST-INFO | 8538: exit 0
03:44:09 INFO - Browser initialized.
03:44:09 INFO - Running cycle 1/1 for basic_compositor_video test...
03:44:09 INFO - Using env: {'DISPLAY': ':0',
03:44:09 INFO - 'HOME': '/home/cltbld',
03:44:09 INFO - 'JSGC_DISABLE_POISONING': '1',
03:44:09 INFO - 'LANG': 'en_US.UTF-8',
03:44:09 INFO - 'LANGUAGE': 'en_US:en',
03:44:09 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:44:09 INFO - 'LOGNAME': 'cltbld',
03:44:09 INFO - 'MAIL': '/var/mail/cltbld',
03:44:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:44:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:44:09 INFO - 'MOZ_CRASHREPORTER': '1',
03:44:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:44:09 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:44:09 INFO - 'MOZ_NO_REMOTE': '1',
03:44:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:44:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:44:09 INFO - 'NO_EM_RESTART': '1',
03:44:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:44:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:44:09 INFO - 'PWD': '/builds/slave/test',
03:44:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:44:09 INFO - 'RUST_BACKTRACE': 'full',
03:44:09 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:44:09 INFO - 'SHELL': '/bin/bash',
03:44:09 INFO - 'SHLVL': '1',
03:44:09 INFO - 'STYLO_FORCE_DISABLED': '1',
03:44:09 INFO - 'TERM': 'linux',
03:44:09 INFO - 'TMOUT': '86400',
03:44:09 INFO - 'USER': 'cltbld',
03:44:09 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:44:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:44:09 INFO - '_': '/tools/buildbot/bin/python'}
03:44:09 INFO - TEST-INFO | started process 8812 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpykulFr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/video/video.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 12)
03:44:10 INFO - PID 8812 |
03:44:10 INFO - PID 8812 | (/builds/slave/test/build/application/firefox/firefox:8864): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:44:10 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6801231802911534 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6686947456213514 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7830154486036842 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7303460207612447 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.06076454668471 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.016686746987951 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.493317811408613 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4860714285714303 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640535714285713 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.731575931232091 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.83680327868852 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.619927884615382 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.585135135135133 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.88622282608697 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.66185654008439 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.050873493975901 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.40973384030419 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.698689839572186 ms/frame
03:44:56 INFO - PID 8812 |
03:44:56 INFO - PID 8812 | Cycle 1(1): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:45:42 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6838945005611672 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6710985797827906 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8085834840265222 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7709026548672557 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 3.9430091984231264 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 3.985986055776893 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.484808362369338 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.414283276450513 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.640451127819551 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.511735537190078 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.585702875399363 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.855467980295566 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.410893536121668 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.992307692307724 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.501416666666682 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.049909638554194 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.860533596837959 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.175893854748606 ms/frame
03:45:42 INFO - PID 8812 |
03:45:42 INFO - PID 8812 | Cycle 1(2): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:46:28 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6887197523916717 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6682527105921598 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7820665083135392 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.751654991243432 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.065474254742547 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.202048319327734 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.542963400236129 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4487413793103454 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.872661448140901 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.482438356164384 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.836049180327866 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.71254854368932 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.584729729729728 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.872880434782612 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.658481012658218 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.768323529411752 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.587374517374506 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.872173913043495 ms/frame
03:46:28 INFO - PID 8812 |
03:46:28 INFO - PID 8812 | Cycle 1(3): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:47:15 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.689338400900901 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6658159866777684 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7883015494636474 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7875960679177836 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.093894952251022 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.203308823529415 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.6194270205066337 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5283333333333373 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.738164435946458 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.682911931818188 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.347554517133963 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.56933014354066 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.406977186311787 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.814081081081099 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.608214285714274 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.979970059880277 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.71974609374999 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.05345303867405 ms/frame
03:47:15 INFO - PID 8812 |
03:47:15 INFO - PID 8812 | Cycle 1(4): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:48:01 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6847052217855139 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6731312709030102 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7987050359712233 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7364887152777773 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.155588642659281 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.228615221987315 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.5681747919143887 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4920157068062814 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.660433962264149 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.6982763532763565 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.837918032786893 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 10.257871794871795 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.19615671641791 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.815108108108138 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.346748971193424 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.20076219512197 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586351351351343 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.814081081081099 ms/frame
03:48:01 INFO - PID 8812 |
03:48:01 INFO - PID 8812 | Cycle 1(5): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:48:47 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6769815539407493 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6701168614357265 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7631903642773208 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7548201754385964 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.220970464135022 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.210873684210524 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.5770560190703233 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5225440140845077 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7148571428571415 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.635718309859156 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.318509316770191 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.903019801980196 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.456145038167946 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.870489130434773 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.552594142259412 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.911279761904769 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.583687258687242 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.93314207650273 ms/frame
03:48:47 INFO - PID 8812 |
03:48:47 INFO - PID 8812 | Cycle 1(6): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:49:10 INFO - PID 8812 |
03:49:10 INFO - PID 8812 | (/builds/slave/test/build/application/firefox/firefox:8943): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:49:10 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6792921096810296 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.668978315262719 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.800753301320528 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7064590443686016 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.115507544581619 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.238930084745762 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.5597568208778174 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4803217391304306 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.59870335820896 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.466379781420764 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.937615894039734 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 10.36305699481867 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.32386792452829 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 11.054502762430968 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.605231092436998 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.701345029239768 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.76774509803922 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.117222222222214 ms/frame
03:49:33 INFO - PID 8812 |
03:49:33 INFO - PID 8812 | Cycle 1(7): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:50:19 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6818553811659191 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.671695906432749 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7827688651218065 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7365451388888882 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.133774104683194 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.149875518672203 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.497540792540792 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.402108843537413 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.79272200772201 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.652005649717507 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.496344936708864 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.758073170731702 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.3216037735849 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.75688172043014 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.60535714285713 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.97940119760482 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.450858778625955 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.994642857142866 ms/frame
03:50:19 INFO - PID 8812 |
03:50:19 INFO - PID 8812 | Cycle 1(8): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:51:05 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6789675433687745 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.675255443886097 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.8023903903903906 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7795729537366547 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.1157407407407405 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.211884210526317 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.6590975609756087 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.5407345132743417 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.546219963031425 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.604229691876748 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 10.138597972972978 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 10.106035353535349 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.453740458015258 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.874836956521746 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.348353909465027 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.907797619047589 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.630426356589124 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 11.053646408839814 ms/frame
03:51:05 INFO - PID 8812 |
03:51:05 INFO - PID 8812 | Cycle 1(9): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:51:51 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6801427771556552 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6713533834586463 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.740548143851508 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.740787641427328 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.214585674157303 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.024748490945676 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.505239485981309 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4616003460207616 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.671606805293011 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.666572237960338 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 10.205425170068029 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.435919811320758 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.407281368821298 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.754059139784932 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.660021097046426 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.048915662650618 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.541711538461543 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.932021857923484 ms/frame
03:51:51 INFO - PID 8812 |
03:51:51 INFO - PID 8812 | Cycle 1(10): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:52:38 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.6799860022396416 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6699332220367278 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7797686832740216 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7629647577092513 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.208401122019636 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.151535269709545 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.509964912280702 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4250085616438324 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.650941619585684 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.452479564032701 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.64852090032154 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 9.760414634146352 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.4995785440613 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.93254098360655 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.298831967213118 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 12.05424698795179 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.322622641509417 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.872092391304369 ms/frame
03:52:38 INFO - PID 8812 |
03:52:38 INFO - PID 8812 | Cycle 1(11): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:53:24 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_startup = 1.68402076318743 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1_inclip = 1.6662531223980022 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_startup = 1.7723981098641466 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_1.1_inclip = 1.7596877748460873 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_startup = 4.094311050477491 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.240p.120fps.mp4_scale_2_inclip = 4.201775210084034 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_startup = 3.4889127906976767 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1_inclip = 3.4677816291161174 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_startup = 5.7819749518304455 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_1.1_inclip = 5.5403739612188385 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_startup = 9.968720930232555 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.480p.60fps.webm_scale_2_inclip = 8.891133333333338 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_startup = 11.322471698113214 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1_inclip = 10.87440217391304 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_startup = 12.398161157024807 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_1.1_inclip = 11.90752976190474 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_startup = 11.586640926640936 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | testsrc.1080p.60fps.mp4_scale_2_inclip = 10.991703296703285 ms/frame
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | Cycle 1(12): loaded http://localhost:46289/tests/video/video_playback.html (next: http://localhost:46289/tests/video/video_playback.html)
03:53:24 INFO - PID 8812 | __start_tp_report
03:53:24 INFO - PID 8812 | _x_x_mozilla_page_load
03:53:24 INFO - PID 8812 | _x_x_mozilla_page_load_details
03:53:24 INFO - PID 8812 | |i|pagename|runs|
03:53:24 INFO - PID 8812 | |0;240p.120fps.mp4_scale_1_startup;1.6801231802911534;1.6838945005611672;1.6887197523916717;1.689338400900901;1.6847052217855139;1.6769815539407493;1.6792921096810296;1.6818553811659191;1.6789675433687745;1.6801427771556552;1.6799860022396416;1.68402076318743
03:53:24 INFO - PID 8812 | |1;240p.120fps.mp4_scale_1_inclip;1.6686947456213514;1.6710985797827906;1.6682527105921598;1.6658159866777684;1.6731312709030102;1.6701168614357265;1.668978315262719;1.671695906432749;1.675255443886097;1.6713533834586463;1.6699332220367278;1.6662531223980022
03:53:24 INFO - PID 8812 | |2;240p.120fps.mp4_scale_1.1_startup;1.7830154486036842;1.8085834840265222;1.7820665083135392;1.7883015494636474;1.7987050359712233;1.7631903642773208;1.800753301320528;1.7827688651218065;1.8023903903903906;1.740548143851508;1.7797686832740216;1.7723981098641466
03:53:24 INFO - PID 8812 | |3;240p.120fps.mp4_scale_1.1_inclip;1.7303460207612447;1.7709026548672557;1.751654991243432;1.7875960679177836;1.7364887152777773;1.7548201754385964;1.7064590443686016;1.7365451388888882;1.7795729537366547;1.740787641427328;1.7629647577092513;1.7596877748460873
03:53:24 INFO - PID 8812 | |4;240p.120fps.mp4_scale_2_startup;4.06076454668471;3.9430091984231264;4.065474254742547;4.093894952251022;4.155588642659281;4.220970464135022;4.115507544581619;4.133774104683194;4.1157407407407405;4.214585674157303;4.208401122019636;4.094311050477491
03:53:24 INFO - PID 8812 | |5;240p.120fps.mp4_scale_2_inclip;4.016686746987951;3.985986055776893;4.202048319327734;4.203308823529415;4.228615221987315;4.210873684210524;4.238930084745762;4.149875518672203;4.211884210526317;4.024748490945676;4.151535269709545;4.201775210084034
03:53:24 INFO - PID 8812 | |6;480p.60fps.webm_scale_1_startup;3.493317811408613;3.484808362369338;3.542963400236129;3.6194270205066337;3.5681747919143887;3.5770560190703233;3.5597568208778174;3.497540792540792;3.6590975609756087;3.505239485981309;3.509964912280702;3.4889127906976767
03:53:24 INFO - PID 8812 | |7;480p.60fps.webm_scale_1_inclip;3.4860714285714303;3.414283276450513;3.4487413793103454;3.5283333333333373;3.4920157068062814;3.5225440140845077;3.4803217391304306;3.402108843537413;3.5407345132743417;3.4616003460207616;3.4250085616438324;3.4677816291161174
03:53:24 INFO - PID 8812 | |8;480p.60fps.webm_scale_1.1_startup;5.640535714285713;5.640451127819551;5.872661448140901;5.738164435946458;5.660433962264149;5.7148571428571415;5.59870335820896;5.79272200772201;5.546219963031425;5.671606805293011;5.650941619585684;5.7819749518304455
03:53:24 INFO - PID 8812 | |9;480p.60fps.webm_scale_1.1_inclip;5.731575931232091;5.511735537190078;5.482438356164384;5.682911931818188;5.6982763532763565;5.635718309859156;5.466379781420764;5.652005649717507;5.604229691876748;5.666572237960338;5.452479564032701;5.5403739612188385
03:53:24 INFO - PID 8812 | |10;480p.60fps.webm_scale_2_startup;9.83680327868852;9.585702875399363;9.836049180327866;9.347554517133963;9.837918032786893;9.318509316770191;9.937615894039734;9.496344936708864;10.138597972972978;10.205425170068029;9.64852090032154;9.968720930232555
03:53:24 INFO - PID 8812 | |11;480p.60fps.webm_scale_2_inclip;9.619927884615382;9.855467980295566;9.71254854368932;9.56933014354066;10.257871794871795;9.903019801980196;10.36305699481867;9.758073170731702;10.106035353535349;9.435919811320758;9.760414634146352;8.891133333333338
03:53:24 INFO - PID 8812 | |12;1080p.60fps.mp4_scale_1_startup;11.585135135135133;11.410893536121668;11.584729729729728;11.406977186311787;11.19615671641791;11.456145038167946;11.32386792452829;11.3216037735849;11.453740458015258;11.407281368821298;11.4995785440613;11.322471698113214
03:53:24 INFO - PID 8812 | |13;1080p.60fps.mp4_scale_1_inclip;10.88622282608697;10.992307692307724;10.872880434782612;10.814081081081099;10.815108108108138;10.870489130434773;11.054502762430968;10.75688172043014;10.874836956521746;10.754059139784932;10.93254098360655;10.87440217391304
03:53:24 INFO - PID 8812 | |14;1080p.60fps.mp4_scale_1.1_startup;12.66185654008439;12.501416666666682;12.658481012658218;12.608214285714274;12.346748971193424;12.552594142259412;12.605231092436998;12.60535714285713;12.348353909465027;12.660021097046426;12.298831967213118;12.398161157024807
03:53:24 INFO - PID 8812 | |15;1080p.60fps.mp4_scale_1.1_inclip;12.050873493975901;12.049909638554194;11.768323529411752;11.979970059880277;12.20076219512197;11.911279761904769;11.701345029239768;11.97940119760482;11.907797619047589;12.048915662650618;12.05424698795179;11.90752976190474
03:53:24 INFO - PID 8812 | |16;1080p.60fps.mp4_scale_2_startup;11.40973384030419;11.860533596837959;11.587374517374506;11.71974609374999;11.586351351351343;11.583687258687242;11.76774509803922;11.450858778625955;11.630426356589124;11.541711538461543;11.322622641509417;11.586640926640936
03:53:24 INFO - PID 8812 | |17;1080p.60fps.mp4_scale_2_inclip;10.698689839572186;11.175893854748606;10.872173913043495;11.05345303867405;10.814081081081099;10.93314207650273;11.117222222222214;10.994642857142866;11.053646408839814;10.932021857923484;10.872092391304369;10.991703296703285
03:53:24 INFO - PID 8812 | __end_tp_report
03:53:24 INFO - PID 8812 | __start_cc_report
03:53:24 INFO - PID 8812 | _x_x_mozilla_cycle_collect,593
03:53:24 INFO - PID 8812 | __end_cc_report
03:53:24 INFO - PID 8812 | __startTimestamp1509101604600__endTimestamp
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | ------- Summary: start -------
03:53:24 INFO - PID 8812 | Number of tests: 18
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#0] 240p.120fps.mp4_scale_1_startup Cycles:12 Average:1.68 Median:1.68 stddev:0.00 (0.2%) stddev-sans-first:0.00
03:53:24 INFO - PID 8812 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#1] 240p.120fps.mp4_scale_1_inclip Cycles:12 Average:1.67 Median:1.67 stddev:0.00 (0.2%) stddev-sans-first:0.00
03:53:24 INFO - PID 8812 | Values: 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7 1.7
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#2] 240p.120fps.mp4_scale_1.1_startup Cycles:12 Average:1.78 Median:1.79 stddev:0.02 (1.1%) stddev-sans-first:0.02
03:53:24 INFO - PID 8812 | Values: 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.8 1.7 1.8 1.8
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#3] 240p.120fps.mp4_scale_1.1_inclip Cycles:12 Average:1.75 Median:1.76 stddev:0.02 (1.3%) stddev-sans-first:0.02
03:53:24 INFO - PID 8812 | Values: 1.7 1.8 1.8 1.8 1.7 1.8 1.7 1.7 1.8 1.7 1.8 1.8
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#4] 240p.120fps.mp4_scale_2_startup Cycles:12 Average:4.12 Median:4.12 stddev:0.08 (1.9%) stddev-sans-first:0.08
03:53:24 INFO - PID 8812 | Values: 4.1 3.9 4.1 4.1 4.2 4.2 4.1 4.1 4.1 4.2 4.2 4.1
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#5] 240p.120fps.mp4_scale_2_inclip Cycles:12 Average:4.15 Median:4.20 stddev:0.09 (2.2%) stddev-sans-first:0.08
03:53:24 INFO - PID 8812 | Values: 4.0 4.0 4.2 4.2 4.2 4.2 4.2 4.1 4.2 4.0 4.2 4.2
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#6] 480p.60fps.webm_scale_1_startup Cycles:12 Average:3.54 Median:3.55 stddev:0.06 (1.6%) stddev-sans-first:0.06
03:53:24 INFO - PID 8812 | Values: 3.5 3.5 3.5 3.6 3.6 3.6 3.6 3.5 3.7 3.5 3.5 3.5
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#7] 480p.60fps.webm_scale_1_inclip Cycles:12 Average:3.47 Median:3.48 stddev:0.04 (1.3%) stddev-sans-first:0.05
03:53:24 INFO - PID 8812 | Values: 3.5 3.4 3.4 3.5 3.5 3.5 3.5 3.4 3.5 3.5 3.4 3.5
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#8] 480p.60fps.webm_scale_1.1_startup Cycles:12 Average:5.69 Median:5.69 stddev:0.09 (1.6%) stddev-sans-first:0.09
03:53:24 INFO - PID 8812 | Values: 5.6 5.6 5.9 5.7 5.7 5.7 5.6 5.8 5.5 5.7 5.7 5.8
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#9] 480p.60fps.webm_scale_1.1_inclip Cycles:12 Average:5.59 Median:5.64 stddev:0.10 (1.7%) stddev-sans-first:0.09
03:53:24 INFO - PID 8812 | Values: 5.7 5.5 5.5 5.7 5.7 5.6 5.5 5.7 5.6 5.7 5.5 5.5
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#10] 480p.60fps.webm_scale_2_startup Cycles:12 Average:9.76 Median:9.74 stddev:0.29 (3.0%) stddev-sans-first:0.30
03:53:24 INFO - PID 8812 | Values: 9.8 9.6 9.8 9.3 9.8 9.3 9.9 9.5 10.1 10.2 9.6 10.0
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#11] 480p.60fps.webm_scale_2_inclip Cycles:12 Average:9.77 Median:9.67 stddev:0.39 (4.0%) stddev-sans-first:0.41
03:53:24 INFO - PID 8812 | Values: 9.6 9.9 9.7 9.6 10.3 9.9 10.4 9.8 10.1 9.4 9.8 8.9
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#12] 1080p.60fps.mp4_scale_1_startup Cycles:12 Average:11.41 Median:11.43 stddev:0.11 (1.0%) stddev-sans-first:0.10
03:53:24 INFO - PID 8812 | Values: 11.6 11.4 11.6 11.4 11.2 11.5 11.3 11.3 11.5 11.4 11.5 11.3
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#13] 1080p.60fps.mp4_scale_1_inclip Cycles:12 Average:10.87 Median:10.87 stddev:0.09 (0.8%) stddev-sans-first:0.09
03:53:24 INFO - PID 8812 | Values: 10.9 11.0 10.9 10.8 10.8 10.9 11.1 10.8 10.9 10.8 10.9 10.9
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#14] 1080p.60fps.mp4_scale_1.1_startup Cycles:12 Average:12.52 Median:12.61 stddev:0.14 (1.1%) stddev-sans-first:0.14
03:53:24 INFO - PID 8812 | Values: 12.7 12.5 12.7 12.6 12.3 12.6 12.6 12.6 12.3 12.7 12.3 12.4
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#15] 1080p.60fps.mp4_scale_1.1_inclip Cycles:12 Average:11.96 Median:12.01 stddev:0.14 (1.1%) stddev-sans-first:0.14
03:53:24 INFO - PID 8812 | Values: 12.1 12.0 11.8 12.0 12.2 11.9 11.7 12.0 11.9 12.0 12.1 11.9
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#16] 1080p.60fps.mp4_scale_2_startup Cycles:12 Average:11.59 Median:11.59 stddev:0.15 (1.3%) stddev-sans-first:0.15
03:53:24 INFO - PID 8812 | Values: 11.4 11.9 11.6 11.7 11.6 11.6 11.8 11.5 11.6 11.5 11.3 11.6
03:53:24 INFO - PID 8812 |
03:53:24 INFO - PID 8812 | [#17] 1080p.60fps.mp4_scale_2_inclip Cycles:12 Average:10.96 Median:10.99 stddev:0.13 (1.2%) stddev-sans-first:0.11
03:53:24 INFO - PID 8812 | Values: 10.7 11.2 10.9 11.1 10.8 10.9 11.1 11.0 11.1 10.9 10.9 11.0
03:53:24 INFO - PID 8812 | -------- Summary: end --------
03:53:24 INFO - PID 8812 |
03:53:25 INFO - TEST-INFO | 8812: exit 0
03:53:25 INFO - TEST-OK | basic_compositor_video | took 562109ms
03:53:25 INFO - TEST-START | glvideo
03:53:25 INFO - Initialising browser for glvideo test...
03:53:25 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html -profile /tmp/tmpVNqhCl/profile
03:53:25 INFO - TEST-INFO | started process 10108 (/builds/slave/test/build/application/firefox/firefox http://localhost:46289/getInfo.html)
03:53:30 INFO - TEST-INFO | 10108: exit 0
03:53:31 INFO - Browser initialized.
03:53:31 INFO - Running cycle 1/1 for glvideo test...
03:53:31 INFO - Using env: {'DISPLAY': ':0',
03:53:31 INFO - 'HOME': '/home/cltbld',
03:53:31 INFO - 'JSGC_DISABLE_POISONING': '1',
03:53:31 INFO - 'LANG': 'en_US.UTF-8',
03:53:31 INFO - 'LANGUAGE': 'en_US:en',
03:53:31 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox',
03:53:31 INFO - 'LOGNAME': 'cltbld',
03:53:31 INFO - 'MAIL': '/var/mail/cltbld',
03:53:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir',
03:53:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk',
03:53:31 INFO - 'MOZ_CRASHREPORTER': '1',
03:53:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1',
03:53:31 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1',
03:53:31 INFO - 'MOZ_NO_REMOTE': '1',
03:53:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir',
03:53:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript',
03:53:31 INFO - 'NO_EM_RESTART': '1',
03:53:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games',
03:53:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json',
03:53:31 INFO - 'PWD': '/builds/slave/test',
03:53:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos',
03:53:31 INFO - 'RUST_BACKTRACE': 'full',
03:53:31 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts',
03:53:31 INFO - 'SHELL': '/bin/bash',
03:53:31 INFO - 'SHLVL': '1',
03:53:31 INFO - 'STYLO_FORCE_DISABLED': '1',
03:53:31 INFO - 'TERM': 'linux',
03:53:31 INFO - 'TMOUT': '86400',
03:53:31 INFO - 'USER': 'cltbld',
03:53:31 INFO - 'XDG_SESSION_COOKIE': 'ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981',
03:53:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn',
03:53:31 INFO - '_': '/tools/buildbot/bin/python'}
03:53:31 INFO - TEST-INFO | started process 10381 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVNqhCl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/webgl/glvideo.manifest.develop -tpnoisy -tploadnocache -tpcycles 1 -tppagecycles 5)
03:53:31 INFO - PID 10381 |
03:53:31 INFO - PID 10381 | (/builds/slave/test/build/application/firefox/firefox:10434): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied
03:53:31 INFO - PID 10381 |
03:53:33 INFO - PID 10381 | [talos glvideo result] Mean tick time across 100 ticks: 7.454350000000002 ms
03:53:33 INFO - PID 10381 | Cycle 1(1): loaded http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html)
03:53:34 INFO - PID 10381 | [talos glvideo result] Mean tick time across 100 ticks: 6.427949999999999 ms
03:53:34 INFO - PID 10381 | Cycle 1(2): loaded http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html)
03:53:36 INFO - PID 10381 | [talos glvideo result] Mean tick time across 100 ticks: 6.4735000000000005 ms
03:53:36 INFO - PID 10381 | Cycle 1(3): loaded http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html)
03:53:37 INFO - PID 10381 | [talos glvideo result] Mean tick time across 100 ticks: 6.6262 ms
03:53:37 INFO - PID 10381 | Cycle 1(4): loaded http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html)
03:53:38 INFO - PID 10381 | [talos glvideo result] Mean tick time across 100 ticks: 6.5992 ms
03:53:38 INFO - PID 10381 | Cycle 1(5): loaded http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html (next: http://localhost:46289/tests/webgl/benchmarks/video/video_upload.html)
03:53:39 INFO - PID 10381 | __start_tp_report
03:53:39 INFO - PID 10381 | _x_x_mozilla_page_load
03:53:39 INFO - PID 10381 | _x_x_mozilla_page_load_details
03:53:39 INFO - PID 10381 | |i|pagename|runs|
03:53:39 INFO - PID 10381 | |0;Mean tick time across 100 ticks: ;7.454350000000002;6.427949999999999;6.4735000000000005;6.6262;6.5992
03:53:39 INFO - PID 10381 | __end_tp_report
03:53:39 INFO - PID 10381 | __start_cc_report
03:53:39 INFO - PID 10381 | _x_x_mozilla_cycle_collect,295
03:53:39 INFO - PID 10381 | __end_cc_report
03:53:39 INFO - PID 10381 | __startTimestamp1509101619049__endTimestamp
03:53:39 INFO - PID 10381 |
03:53:39 INFO - PID 10381 | ------- Summary: start -------
03:53:39 INFO - PID 10381 | Number of tests: 1
03:53:39 INFO - PID 10381 |
03:53:39 INFO - PID 10381 | [#0] Mean tick time across 100 ticks: Cycles:5 Average:6.72 Median:6.60 stddev:0.42 (6.4%) stddev-sans-first:0.10
03:53:39 INFO - PID 10381 | Values: 7.5 6.4 6.5 6.6 6.6
03:53:39 INFO - PID 10381 | -------- Summary: end --------
03:53:39 INFO - PID 10381 |
03:53:39 INFO - PID 10381 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping
03:53:39 INFO - TEST-INFO | 10381: exit 0
03:53:39 INFO - TEST-OK | glvideo | took 14588ms
03:53:39 INFO - SUITE-END | took 576s
03:53:40 INFO - Completed test suite (00:09:37)
03:53:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s", "stylo_disabled"], "name": "basic_compositor_video", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 5.619711579595729, "subtests": [{"name": "240p.120fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6801231802911534, 1.6838945005611672, 1.6887197523916717, 1.689338400900901, 1.6847052217855139, 1.6769815539407493, 1.6792921096810296, 1.6818553811659191, 1.6789675433687745, 1.6801427771556552, 1.6799860022396416, 1.68402076318743], "value": 1.6818553811659191, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.6686947456213514, 1.6710985797827906, 1.6682527105921598, 1.6658159866777684, 1.6731312709030102, 1.6701168614357265, 1.668978315262719, 1.671695906432749, 1.675255443886097, 1.6713533834586463, 1.6699332220367278, 1.6662531223980022], "value": 1.6701168614357265, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7830154486036842, 1.8085834840265222, 1.7820665083135392, 1.7883015494636474, 1.7987050359712233, 1.7631903642773208, 1.800753301320528, 1.7827688651218065, 1.8023903903903906, 1.740548143851508, 1.7797686832740216, 1.7723981098641466], "value": 1.7827688651218065, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [1.7303460207612447, 1.7709026548672557, 1.751654991243432, 1.7875960679177836, 1.7364887152777773, 1.7548201754385964, 1.7064590443686016, 1.7365451388888882, 1.7795729537366547, 1.740787641427328, 1.7629647577092513, 1.7596877748460873], "value": 1.7548201754385964, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.06076454668471, 3.9430091984231264, 4.065474254742547, 4.093894952251022, 4.155588642659281, 4.220970464135022, 4.115507544581619, 4.133774104683194, 4.1157407407407405, 4.214585674157303, 4.208401122019636, 4.094311050477491], "value": 4.1157407407407405, "unit": "ms/frame"}, {"name": "240p.120fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [4.016686746987951, 3.985986055776893, 4.202048319327734, 4.203308823529415, 4.228615221987315, 4.210873684210524, 4.238930084745762, 4.149875518672203, 4.211884210526317, 4.024748490945676, 4.151535269709545, 4.201775210084034], "value": 4.202048319327734, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.493317811408613, 3.484808362369338, 3.542963400236129, 3.6194270205066337, 3.5681747919143887, 3.5770560190703233, 3.5597568208778174, 3.497540792540792, 3.6590975609756087, 3.505239485981309, 3.509964912280702, 3.4889127906976767], "value": 3.542963400236129, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [3.4860714285714303, 3.414283276450513, 3.4487413793103454, 3.5283333333333373, 3.4920157068062814, 3.5225440140845077, 3.4803217391304306, 3.402108843537413, 3.5407345132743417, 3.4616003460207616, 3.4250085616438324, 3.4677816291161174], "value": 3.4677816291161174, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.640535714285713, 5.640451127819551, 5.872661448140901, 5.738164435946458, 5.660433962264149, 5.7148571428571415, 5.59870335820896, 5.79272200772201, 5.546219963031425, 5.671606805293011, 5.650941619585684, 5.7819749518304455], "value": 5.671606805293011, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [5.731575931232091, 5.511735537190078, 5.482438356164384, 5.682911931818188, 5.6982763532763565, 5.635718309859156, 5.466379781420764, 5.652005649717507, 5.604229691876748, 5.666572237960338, 5.452479564032701, 5.5403739612188385], "value": 5.604229691876748, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.83680327868852, 9.585702875399363, 9.836049180327866, 9.347554517133963, 9.837918032786893, 9.318509316770191, 9.937615894039734, 9.496344936708864, 10.138597972972978, 10.205425170068029, 9.64852090032154, 9.968720930232555], "value": 9.836049180327866, "unit": "ms/frame"}, {"name": "480p.60fps.webm_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [9.619927884615382, 9.855467980295566, 9.71254854368932, 9.56933014354066, 10.257871794871795, 9.903019801980196, 10.36305699481867, 9.758073170731702, 10.106035353535349, 9.435919811320758, 9.760414634146352, 8.891133333333338], "value": 9.760414634146352, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.585135135135133, 11.410893536121668, 11.584729729729728, 11.406977186311787, 11.19615671641791, 11.456145038167946, 11.32386792452829, 11.3216037735849, 11.453740458015258, 11.407281368821298, 11.4995785440613, 11.322471698113214], "value": 11.407281368821298, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.88622282608697, 10.992307692307724, 10.872880434782612, 10.814081081081099, 10.815108108108138, 10.870489130434773, 11.054502762430968, 10.75688172043014, 10.874836956521746, 10.754059139784932, 10.93254098360655, 10.87440217391304], "value": 10.872880434782612, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.66185654008439, 12.501416666666682, 12.658481012658218, 12.608214285714274, 12.346748971193424, 12.552594142259412, 12.605231092436998, 12.60535714285713, 12.348353909465027, 12.660021097046426, 12.298831967213118, 12.398161157024807], "value": 12.552594142259412, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_1.1_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [12.050873493975901, 12.049909638554194, 11.768323529411752, 11.979970059880277, 12.20076219512197, 11.911279761904769, 11.701345029239768, 11.97940119760482, 11.907797619047589, 12.048915662650618, 12.05424698795179, 11.90752976190474], "value": 11.97940119760482, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_startup", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [11.40973384030419, 11.860533596837959, 11.587374517374506, 11.71974609374999, 11.586351351351343, 11.583687258687242, 11.76774509803922, 11.450858778625955, 11.630426356589124, 11.541711538461543, 11.322622641509417, 11.586640926640936], "value": 11.586640926640936, "unit": "ms/frame"}, {"name": "1080p.60fps.mp4_scale_2_inclip", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [10.698689839572186, 11.175893854748606, 10.872173913043495, 11.05345303867405, 10.814081081081099, 10.93314207650273, 11.117222222222214, 10.994642857142866, 11.053646408839814, 10.932021857923484, 10.872092391304369, 10.991703296703285], "value": 10.991703296703285, "unit": "ms/frame"}]}, {"lowerIsBetter": true, "subtests": [{"name": "Mean tick time across 100 ticks: ", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [7.454350000000002, 6.427949999999999, 6.4735000000000005, 6.6262, 6.5992], "value": 6.5363500000000005, "unit": "ms"}], "extraOptions": ["e10s", "stylo_disabled"], "name": "glvideo", "alertThreshold": 2.0}]}
03:53:40 INFO - Return code: 0
03:53:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json
03:53:40 INFO - # TBPL SUCCESS #
03:53:40 INFO - Running post-action listener: _package_coverage_data
03:53:40 INFO - Running post-action listener: _resource_record_post_action
03:53:40 INFO - [mozharness: 2017-10-27 10:53:40.184384Z] Finished run-tests step (success)
03:53:40 INFO - Running post-run listener: _resource_record_post_run
03:53:40 INFO - Total resource usage - Wall time: 596s; CPU: 39.0%; Read bytes: 8908800; Write bytes: 317595648; Read time: 340; Write time: 244828
03:53:40 INFO - TinderboxPrint: CPU usage
38.7%
03:53:40 INFO - TinderboxPrint: I/O read bytes / time
8,908,800 / 340
03:53:40 INFO - TinderboxPrint: I/O write bytes / time
317,595,648 / 244,828
03:53:40 INFO - TinderboxPrint: CPU idle
2,866.3 (61.3%)
03:53:40 INFO - TinderboxPrint: CPU system
303.4 (6.5%)
03:53:40 INFO - TinderboxPrint: CPU user
1,489.1 (31.9%)
03:53:40 INFO - TinderboxPrint: Swap in / out
0 / 0
03:53:40 INFO - install - Wall time: 19s; CPU: 13.0%; Read bytes: 0; Write bytes: 3563520; Read time: 0; Write time: 4628
03:53:40 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
03:53:40 INFO - run-tests - Wall time: 578s; CPU: 40.0%; Read bytes: 8908800; Write bytes: 311373824; Read time: 340; Write time: 239964
03:53:40 INFO - Running post-run listener: _upload_blobber_files
03:53:40 INFO - Blob upload gear active.
03:53:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir.
03:53:40 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com
03:53:40 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']
03:53:40 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
03:53:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:334: SNIMissingWarning: An HTTPS request has been made, but the SNI (Subject Name Indication) extension to TLS is not available on this platform. This may cause the server to present an incorrect TLS certificate, which can cause validation failures. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:40 INFO - SNIMissingWarning
03:53:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:40 INFO - InsecurePlatformWarning
03:53:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:53:40 INFO - SubjectAltNameWarning
03:53:40 INFO - (blobuploader) - INFO - Open directory for files ...
03:53:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ...
03:53:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:53:40 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:53:40 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:40 INFO - InsecurePlatformWarning
03:53:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:53:41 INFO - SubjectAltNameWarning
03:53:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:41 INFO - InsecurePlatformWarning
03:53:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded
03:53:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:53:41 INFO - (blobuploader) - INFO - Done attempting.
03:53:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_errorsummary.log ...
03:53:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:53:41 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:53:41 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:41 INFO - InsecurePlatformWarning
03:53:42 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:53:42 INFO - SubjectAltNameWarning
03:53:42 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:42 INFO - InsecurePlatformWarning
03:53:42 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_errorsummary.log: uploaded
03:53:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:53:42 INFO - (blobuploader) - INFO - Done attempting.
03:53:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/g4-stylo-disabled-e10s_raw.log ...
03:53:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:53:42 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:53:42 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:42 INFO - InsecurePlatformWarning
03:53:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:53:43 INFO - SubjectAltNameWarning
03:53:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:43 INFO - InsecurePlatformWarning
03:53:43 INFO - (blobuploader) - INFO - TinderboxPrint: g4-stylo-disabled-e10s_raw.log: uploaded
03:53:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:53:43 INFO - (blobuploader) - INFO - Done attempting.
03:53:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ...
03:53:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com
03:53:43 INFO - (blobuploader) - INFO - Uploading, attempt #1.
03:53:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:43 INFO - InsecurePlatformWarning
03:53:43 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/connection.py:340: SubjectAltNameWarning: Certificate for blobupload.elasticbeanstalk.com has no `subjectAltName`, falling back to check for a `commonName` for now. This feature is being removed by major browsers and deprecated by RFC 2818. (See https://github.com/shazow/urllib3/issues/497 for details.)
03:53:43 INFO - SubjectAltNameWarning
03:53:48 INFO - /builds/slave/test/build/venv/lib/python2.7/site-packages/requests/packages/urllib3/util/ssl_.py:132: InsecurePlatformWarning: A true SSLContext object is not available. This prevents urllib3 from configuring SSL appropriately and may cause certain SSL connections to fail. You can upgrade to a newer version of Python to solve this. For more information, see https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
03:53:48 INFO - InsecurePlatformWarning
03:53:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded
03:53:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded!
03:53:48 INFO - (blobuploader) - INFO - Done attempting.
03:53:48 INFO - (blobuploader) - INFO - Iteration through files over.
03:53:48 INFO - Return code: 0
03:53:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json
03:53:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1
03:53:48 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/72ef0a820a012ee580379369d8062d01384c8e3b16aacbd02e5432e7a83f76c9eb8e80842ae89c63d484b7bf91db3f63b7dac3fd657d08143ad893f7f9352513", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71bf35dd56bbd60ab115213cf025d98d91d54cdbd6659548ada423e03825b4e182315608fd2af6fa041bbe046105e2a324af9fe1cd648908bcd40a1773754d0b", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badef7e0ebbba705171382bb5e593a8976724b01b59c29f722d89a48836a6544dd6de2fc230f7e40d82009140727700bdcc2a5bd37969232871551093efb7b72", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f7ffe08806e1792e976db3cdda83767097a8fa342c2c46ca663cf2e63c90351dcb512731af0bbb4c4e02bf8468bddf2205d4a5d4ea441d07b8342d38c5cca307"}
03:53:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files
03:53:48 INFO - Writing to file /builds/slave/test/properties/blobber_files
03:53:48 INFO - Contents:
03:53:48 INFO - blobber_files:{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/72ef0a820a012ee580379369d8062d01384c8e3b16aacbd02e5432e7a83f76c9eb8e80842ae89c63d484b7bf91db3f63b7dac3fd657d08143ad893f7f9352513", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71bf35dd56bbd60ab115213cf025d98d91d54cdbd6659548ada423e03825b4e182315608fd2af6fa041bbe046105e2a324af9fe1cd648908bcd40a1773754d0b", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badef7e0ebbba705171382bb5e593a8976724b01b59c29f722d89a48836a6544dd6de2fc230f7e40d82009140727700bdcc2a5bd37969232871551093efb7b72", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f7ffe08806e1792e976db3cdda83767097a8fa342c2c46ca663cf2e63c90351dcb512731af0bbb4c4e02bf8468bddf2205d4a5d4ea441d07b8342d38c5cca307"}
03:53:48 INFO - Running post-run listener: copy_logs_to_upload_dir
03:53:48 INFO - Copying logs to upload dir...
03:53:48 INFO - mkdir: /builds/slave/test/build/upload/logs
03:53:48 INFO - Copying logs to upload dir...
program finished with exit code 0
elapsedTime=674.037820
========= master_lag: 0.18 =========
========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 11 mins, 14 secs) (at 2017-10-27 03:53:48.648660) =========
========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-27 03:53:48.651730) =========
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=ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981
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/72ef0a820a012ee580379369d8062d01384c8e3b16aacbd02e5432e7a83f76c9eb8e80842ae89c63d484b7bf91db3f63b7dac3fd657d08143ad893f7f9352513", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71bf35dd56bbd60ab115213cf025d98d91d54cdbd6659548ada423e03825b4e182315608fd2af6fa041bbe046105e2a324af9fe1cd648908bcd40a1773754d0b", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badef7e0ebbba705171382bb5e593a8976724b01b59c29f722d89a48836a6544dd6de2fc230f7e40d82009140727700bdcc2a5bd37969232871551093efb7b72", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f7ffe08806e1792e976db3cdda83767097a8fa342c2c46ca663cf2e63c90351dcb512731af0bbb4c4e02bf8468bddf2205d4a5d4ea441d07b8342d38c5cca307"}
build_url:https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2
program finished with exit code 0
elapsedTime=0.011172
build_url: 'https://queue.taskcluster.net/v1/task/VQ1N5TAgQn61fQ4Cn4LxFw/artifacts/public/build/target.tar.bz2'
blobber_files: '{"perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/72ef0a820a012ee580379369d8062d01384c8e3b16aacbd02e5432e7a83f76c9eb8e80842ae89c63d484b7bf91db3f63b7dac3fd657d08143ad893f7f9352513", "g4-stylo-disabled-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71bf35dd56bbd60ab115213cf025d98d91d54cdbd6659548ada423e03825b4e182315608fd2af6fa041bbe046105e2a324af9fe1cd648908bcd40a1773754d0b", "g4-stylo-disabled-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badef7e0ebbba705171382bb5e593a8976724b01b59c29f722d89a48836a6544dd6de2fc230f7e40d82009140727700bdcc2a5bd37969232871551093efb7b72", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/f7ffe08806e1792e976db3cdda83767097a8fa342c2c46ca663cf2e63c90351dcb512731af0bbb4c4e02bf8468bddf2205d4a5d4ea441d07b8342d38c5cca307"}'
========= master_lag: 0.02 =========
========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-27 03:53:48.680899) =========
========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-27 03:53:48.681179) =========
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=ca619d3d7d62c99d832f7b8a000001c4-1509094755.384927-176502981
XPCOM_DEBUG_BREAK=warn
_=/tools/buildbot/bin/python
using PTY: False
program finished with exit code 0
elapsedTime=0.004923
========= master_lag: 0.05 =========
========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-27 03:53:48.731456) =========
========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-27 03:53:48.731779) =========
========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-27 03:53:48.732087) =========
========= Total master_lag: 1.22 =========